Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.2022 01: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 14938300 kB
MemAvailable 30122516 kB
Buffers 0 kB
Cached 16789260 kB
SwapCached 3432 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-12-11 01:38:35
DTS_END                         2022-12-11 01:38:35.2010
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/52324
TRN_ID                          239575
ATT_ID                          664
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: 9379.39 27335 1125527
customer order: creation 166.20 381 19944
customer order: refuse 14.38 371 1726
order to supplier: creation 46.02 1230 5522
order to supplier: removal 3.69 97716 443
invoice (draft): creation 45.20 3409 5424
invoice (draft): removal 55.80 802 6696
invoice accept: apply 40.92 17633 4911
invoice accept: cancel 50.58 737 6069
customer reserve: creation 3742.50 176 449100
customer reserve: removal 4919.99 57 590399
realization accept: apply 79.66 199 9559
realization accept: cancel 87.83 117 10539
payment from customer: creation 58.01 124 6961
payment from customer: removal 5.48 83 658
payment to supplier: creation 47.79 110 5735
payment to supplier: removal 3.26 90 391
service: total inventory turnovers 4.64 1150 557
service: total monetary turnovers 6.58 38 790
service: aggregate perf. data 0.83 21500 99
service: refresh index statistics 0.03 400769 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-12-2022 01:40:12 to 11-12-2022 01:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5011
TEST_TIME 2 3094
TEST_TIME 3 4144
TEST_TIME 4 4140
TEST_TIME 5 4172
TEST_TIME 6 4601
TEST_TIME 7 4768
TEST_TIME 8 5033
TEST_TIME 9 5334
TEST_TIME 10 5663
TEST_TIME 11 5972
TEST_TIME 12 5976
TEST_TIME 13 5995
TEST_TIME 14 6659
TEST_TIME 15 6571
TEST_TIME 16 6895
TEST_TIME 17 7504
TEST_TIME 18 7736
TEST_TIME 19 7996
TEST_TIME 20 8019
TEST_TIME 21 8627
TEST_TIME 22 8566
TEST_TIME 23 8962
TEST_TIME 24 9190
TEST_TIME 25 9237
TEST_TIME 26 10287
TEST_TIME 27 11161
TEST_TIME 28 10925
TEST_TIME 29 10992
TEST_TIME 30 11017
TEST_TIME 31 10773
TEST_TIME 32 10599
TEST_TIME 33 10555
TEST_TIME 34 10570
TEST_TIME 35 10381
TEST_TIME 36 10288
TEST_TIME 37 10154
TEST_TIME 38 10190
TEST_TIME 39 10199
TEST_TIME 40 10208
TEST_TIME 41 10206
TEST_TIME 42 10191
TEST_TIME 43 10083
TEST_TIME 44 10002
TEST_TIME 45 9939
TEST_TIME 46 9904
TEST_TIME 47 9834
TEST_TIME 48 9785
TEST_TIME 49 9659
TEST_TIME 50 9591
TEST_TIME 51 9521
TEST_TIME 52 9436
TEST_TIME 53 9439
TEST_TIME 54 9459
TEST_TIME 55 9587
TEST_TIME 56 9673
TEST_TIME 57 9747
TEST_TIME 58 9699
TEST_TIME 59 9680
TEST_TIME 60 9733
TEST_TIME 61 9838
TEST_TIME 62 9901
TEST_TIME 63 9863
TEST_TIME 64 9859
TEST_TIME 65 9782
TEST_TIME 66 9745
TEST_TIME 67 9667
TEST_TIME 68 9700
TEST_TIME 69 9732
TEST_TIME 70 9726
TEST_TIME 71 9703
TEST_TIME 72 9745
TEST_TIME 73 9678
TEST_TIME 74 9762
TEST_TIME 75 9766
TEST_TIME 76 9788
TEST_TIME 77 9796
TEST_TIME 78 9736
TEST_TIME 79 9822
TEST_TIME 80 9911
TEST_TIME 81 9870
TEST_TIME 82 9807
TEST_TIME 83 9772
TEST_TIME 84 9708
TEST_TIME 85 9689
TEST_TIME 86 9701
TEST_TIME 87 9667
TEST_TIME 88 9623
TEST_TIME 89 9636
TEST_TIME 90 9596
TEST_TIME 91 9574
TEST_TIME 92 9523
TEST_TIME 93 9541
TEST_TIME 94 9524
TEST_TIME 95 9506
TEST_TIME 96 9514
TEST_TIME 97 9554
TEST_TIME 98 9551
TEST_TIME 99 9529
TEST_TIME 100 9485
TEST_TIME 101 9534
TEST_TIME 102 9692
TEST_TIME 103 9710
TEST_TIME 104 9684
TEST_TIME 105 9712
TEST_TIME 106 9709
TEST_TIME 107 9717
TEST_TIME 108 9671
TEST_TIME 109 9600
TEST_TIME 110 9606
TEST_TIME 111 9572
TEST_TIME 112 9564
TEST_TIME 113 9534
TEST_TIME 114 9516
TEST_TIME 115 9499
TEST_TIME 116 9458
TEST_TIME 117 9442
TEST_TIME 118 9371
TEST_TIME 119 9397
TEST_TIME 120 9390

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-12-2022 01:40:16 to 11-12-2022 01:40:19.

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 1542197 1542197 0 0.00 0 26323 6 0 0
doc_list_biud 1542197 1542197 0 0.00 0 79488 28 0 0
sp_add_invoice_to_stock 5047 4911 136 2.69 37 191202 17634 0 136
sp_cancel_adding_invoice 6069 6069 0 0.00 4 85524 738 0 0
sp_cancel_client_order 1726 1726 0 0.00 4 10996 372 0 0
sp_cancel_customer_reserve 590399 590399 0 0.00 0 36513 57 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 2 6324 83 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 2 3740 91 0 0
sp_cancel_supplier_invoice 6704 6696 8 0.11 1 79492 803 0 8
sp_cancel_supplier_order 443 443 0 0.00 6 1131266 97716 0 0
sp_cancel_write_off 10539 10539 0 0.00 1 16929 117 0 0
sp_client_order 19944 19944 0 0.00 3 28277 381 0 0
sp_customer_reserve 449100 449100 0 0.00 0 85335 176 0 0
sp_fill_shopping_cart 30873 30873 0 0.00 1 9783 38 0 0
sp_fill_shopping_cart_clo_res 449094 449094 0 0.00 0 12094 13 0 0
sp_get_clo_for_invoice 4891 4891 0 0.00 0 18370 292 0 0
sp_kill_cost_storno 17657 17657 0 0.00 0 9324 53 0 0
sp_kill_qstorno_ret_qs2qd 1198476 1198476 0 0.00 0 79486 31 0 0
sp_kill_qty_storno 630320 630320 0 0.00 0 79487 67 0 0
sp_lock_selected_doc 20159 20159 0 0.00 0 10287 6 0 0
sp_make_cost_storno 27144 27144 0 0.00 0 23014 55 0 0
sp_multiply_rows_for_pdistr 27144 27144 0 0.00 0 12209 28 0 0
sp_multiply_rows_for_qdistr 19944 19944 0 0.00 0 23524 156 0 0
sp_pay_from_customer 6961 6961 0 0.00 4 23568 125 0 0
sp_pay_to_supplier 5735 5735 0 0.00 5 10625 111 0 0
sp_payment_common 12696 12696 0 0.00 1 12271 27 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 10975 340 0 0
sp_qd_handle_on_invoice_upd_sts 10958 10958 0 0.00 0 27400 324 0 0
sp_qd_handle_on_reserve_upd_sts 9559 9559 0 0.00 0 26506 68 0 0
sp_reserve_write_off 9559 9559 0 0.00 6 26545 199 0 0
sp_supplier_invoice 5424 5424 0 0.00 5 74386 3409 0 0
sp_supplier_order 5522 5522 0 0.00 4 34369 1230 0 0
srv_aggregate_perf_data 99 99 0 0.00 2473 314235 21501 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1194 122868 45363 0 0
srv_find_qd_qs_mism 1079397 1079397 0 0.00 0 26321 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 38 28292 1151 0 0
srv_make_money_saldo 790 790 0 0.00 1 8575 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 238029 536737 400769 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 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 4911 4911 0 0.00 0 14047 182 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 4707 107 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 1403 9 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1509 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 8222 33 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 6176 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1754 29 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 23427 1500 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 1662 7 0 0
t$perf-norm:sp_client_order 19944 19944 0 0.00 0 16304 20 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 1317 11 0 0
t$perf-norm:sp_pay_from_customer 6961 6961 0 0.00 0 10911 12 0 0
t$perf-norm:sp_pay_to_supplier 5735 5735 0 0.00 0 6538 16 0 0
t$perf-norm:sp_reserve_write_off 9559 9559 0 0.00 0 13338 14 0 0
t$perf-norm:sp_supplier_invoice 5424 5424 0 0.00 0 6444 22 0 0
t$perf-norm:sp_supplier_order 5522 5522 0 0.00 0 5536 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 77 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 2445 33 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 1367 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 10050 9778 272 2.70 0 8756 20 0 272
v_all_customers 36794 36794 0 0.00 0 21 1 0 0
v_all_suppliers 30028 30028 0 0.00 0 46 1 0 0
v_all_wares 39888 39888 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 885 7 0 0
v_cancel_client_order 3452 3452 0 0.00 0 2067 7 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 4249 9 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 782 4 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 2066 9 0 0
v_cancel_supplier_invoice 800 784 16 2.00 0 4644 24 0 16
v_cancel_supplier_order 886 886 0 0.00 0 1998 10 0 0
v_cancel_write_off 1972 1972 0 0.00 0 3311 16 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 1212 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 14585 37 0 0
v_max_id_ord_sup 5407 5407 0 0.00 0 8477 7 0 0
v_max_non_paid_invoice 1650 1650 0 0.00 0 2152 4 0 0
v_max_non_paid_realizn 3576 3576 0 0.00 0 4633 5 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 2900 6 0 0
v_min_id_clo_res 965 965 0 0.00 0 6441 11 0 0
v_min_id_ord_sup 5407 5407 0 0.00 0 6987 6 0 0
v_min_non_paid_invoice 5735 5735 0 0.00 0 10592 17 0 0
v_min_non_paid_realizn 6961 6961 0 0.00 0 12267 19 0 0
v_our_firm 9864 9864 0 0.00 0 22 1 0 0
v_reserve_write_off 19118 19118 0 0.00 0 8481 7 0 0
x_lock_depdocs_on_canc_invoice 12373 12373 0 0.00 0 16214 119 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 27371 423 0 0
x_make_qstorno_1000_1200 5522 5522 0 0.00 1 34348 1158 0 0
x_make_qstorno_1200_2000 5424 5424 0 0.00 2 74340 3268 0 0
x_make_qstorno_2100_3300 449100 449100 0 0.00 0 85318 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2022 01:40:20 to 11-12-2022 01:40:24.

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
23:08:39 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:39 12886 8192000000 9879426576 10185949184 965717424 10.545 100 87 174 0 994852352 5671184 23463744
23:11:02 20472 8192000000 9940844096 10258628608 1018350944 11.056 100 92 184 0 1053809904 11648672 23810288
23:12:25 16519 8192000000 9971223488 10299736064 1054011504 11.399 100 85 170 0 1084509888 8990432 21507952
23:13:45 8680 8192000000 10018681728 10361106432 1102777824 11.864 100 81 162 0 1132940912 10366912 19796176
23:15:04 6066 8192000000 10087498128 10447872000 1180794080 12.598 100 76 151 0 1209672640 11384960 17493600
23:16:12 4199 8192000000 10136794448 10505789440 1224317376 13.002 100 85 170 0 1256825376 13432432 19075568
23:17:19 2462 8192000000 10169047216 10545680384 1252690240 13.263 100 89 178 1 1284512752 12247280 19575232
23:18:23 6354 8192000000 10183255920 10568253440 1275317680 13.470 100 74 145 1 1303317392 12438896 15560816
23:19:31 1269 8192000000 10213132912 10604691456 1301715024 13.711 100 92 184 2 1335945648 12106896 22123728
23:20:35 33434 8192000000 10195962256 10609025024 1308772512 13.775 100 33 66 1 1323301632 7177296 7351824
23:22:09 7115 8192000000 10196467120 10615951360 1313686592 13.820 100 24 48 0 1324788784 5844064 5258128
23:23:20 14160 8192000000 10207797344 10629251072 1323478288 13.908 100 14 27 1 1331391296 5241568 2671440
23:24:35 54879 8192000000 10217841232 10646224896 1330721920 13.974 100 17 34 0 1339787088 5001728 4063440
23:26:31 5505 8192000000 10231895776 10647425024 1333470464 13.998 100 34 68 0 1347603840 6806272 7327104
23:27:37 6259 8192000000 10218452224 10641010688 1323528000 13.909 99 23 47 0 1335025312 6089552 5407760
23:28:47 8582 8192000000 10214678272 10638577664 1326798544 13.938 100 17 34 0 1340518528 10293520 3426464
23:30:32 3956 8192000000 10172640624 10574974976 1248615088 13.225 97 27 53 1 1263712000 9431952 5664960
23:31:36 9096 8192000000 10137872704 10531106816 1180403712 12.594 94 16 32 0 1188627680 5049872 3174096
23:32:49 7126 8192000000 10152595216 10546511872 1240755344 13.153 99 26 52 0 1252906544 5432576 6718624
23:34:00 51700 8192000000 10144184624 10552717312 1248233376 13.222 99 21 42 0 1258085872 5083792 4768704
23:35:56 4323 8192000000 10177316608 10580574208 1279139488 13.505 100 31 61 0 1291965280 6006944 6818848
23:37:04 11047 8192000000 10186772128 10600460288 1295078144 13.650 100 10 20 0 1303409408 5451312 2879952
23:38:16 93723 8192000000 10239965632 10651897856 1329929504 13.967 100 43 84 0 1346190512 5770912 10490096
23:40:55 15324 8192000000 10260419168 10676596736 1350863744 14.155 100 49 96 0 1372258848 10924400 10470704
23:42:13 11667 8192000000 10279227392 10697551872 1369792496 14.325 100 40 81 1 1393307536 14117872 9397168
23:43:27 9134 8192000000 10302118704 10720636928 1384378832 14.456 100 66 133 1 1416433520 16355888 15698800
23:44:44 15897 8192000000 10312366272 10729992192 1389525920 14.502 100 57 114 1 1426339680 23304064 13509696
23:46:11 6119 8192000000 10324096544 10742263808 1399302864 14.589 100 55 110 0 1435084432 22775008 13006560
23:47:32 8157 8192000000 10327543392 10754314240 1395685040 14.557 99 49 98 0 1431574864 23533488 12356336
23:48:45 7764 8192000000 10340632320 10770014208 1413247536 14.713 100 50 99 0 1454598304 28032576 13318192
23:49:57 5200 8192000000 10352460848 10777137152 1409001120 14.675 99 57 111 1 1451379312 28642496 13735696
23:51:08 10491 8192000000 10363319520 10786058240 1406385840 14.652 99 63 124 1 1458593120 36645888 15561392
23:52:31 2921 8192000000 10353776064 10768351232 1408403424 14.670 100 78 155 1 1461865344 34825200 18636720
23:53:39 9954 8192000000 10364065872 10775879680 1397941424 14.577 98 72 144 3 1463693216 48280608 17471184
23:54:58 3372 8192000000 10389905888 10802380800 1414825264 14.727 100 86 169 0 1491033824 55380112 20828448
23:56:07 1194 8192000000 10359110336 10778804224 1400564960 14.600 100 68 130 0 1464642240 47147904 16929376
23:57:14 6115 8192000000 10370623552 10790088704 1393751168 14.539 99 59 118 0 1461484992 53022272 14711552
23:58:25 22080 8192000000 10353637456 10779459584 1374051216 14.363 98 41 82 1 1444387856 58908768 11427872
23:59:53 25314 8192000000 10332568400 10769907712 1384641968 14.458 100 28 56 0 1441602016 49050880 7909168
00:01:26 26366 8192000000 10333199408 10767765504 1366966448 14.300 99 33 66 0 1431210352 54560400 9683504
00:02:53 15937 8192000000 10321067440 10754609152 1347632800 14.126 99 25 50 2 1410725968 56181984 6911184
00:04:14 27337 8192000000 10306582048 10749718528 1360484352 14.242 99 26 50 1 1419185024 50724176 7976496
00:05:57 31041 8192000000 10268506640 10703179776 1318811168 13.866 97 24 49 0 1336828464 12471904 5545392
00:07:31 22925 8192000000 10278261760 10723958784 1352074032 14.166 99 27 54 0 1376771360 18304896 6392432
00:09:02 118763 8192000000 10286584416 10729795584 1364316672 14.276 99 42 84 1 1382725040 8585840 9822528
00:12:09 45004 8192000000 10286668272 10728558592 1338989616 14.048 97 27 53 0 1351678720 6738432 5950672
00:13:59 41280 8192000000 10316853280 10757238784 1396240592 14.562 100 29 56 1 1419408576 16428960 6739024
00:15:42 57624 8192000000 10315554624 10753691648 1384850176 14.460 99 32 64 0 1410291856 17232192 8209488
00:17:45 79060 8192000000 10295858896 10754953216 1376920368 14.389 98 18 37 2 1404565392 23236432 4408592
00:20:05 44971 8192000000 10292462080 10755313664 1379030688 14.408 99 15 29 0 1395955920 13422944 3502288
00:21:51 64509 8192000000 10293694544 10751258624 1393038944 14.533 100 17 34 0 1405767120 9100512 3627664
00:24:00 14380 8192000000 10292624944 10747006976 1380204224 14.418 99 11 22 1 1391733136 9316352 2212560
00:25:14 30259 8192000000 10300097808 10757816320 1402806288 14.620 100 20 40 0 1414424864 7205200 4413376
00:27:00 96610 8192000000 10275755552 10736721920 1382815776 14.442 99 13 26 0 1392327920 6826288 2685856
00:29:52 51614 8192000000 10299604896 10760122368 1385449344 14.465 99 17 33 0 1397070832 7393920 4227568
00:31:47 69712 8192000000 10308627200 10775154688 1394833920 14.549 99 13 26 0 1413837440 15602176 3401344
00:34:07 96932 8192000000 10293186368 10756583424 1306900208 13.758 93 14 28 0 1322746816 12803504 3043104
00:37:32 31130 8192000000 10278571376 10724995072 1349624832 14.144 97 37 74 0 1372860048 14983072 8252144
00:39:05 71728 8192000000 10284038144 10734329856 1322447696 13.899 96 8 16 0 1335006400 11213280 1345424
00:41:29 43087 8192000000 10286584976 10733490176 1341046688 14.067 97 35 70 0 1361654128 13260976 7346464
00:44:37 80061 8192000000 10295720320 10760114176 1383677168 14.449 100 9 18 0 1409074304 23408048 1989088
00:47:03 104441 8192000000 10263950640 10719035392 1286165984 13.569 93 8 16 0 1306536352 18595600 1774768
00:49:55 60078 8192000000 10282593104 10729345024 1351540176 14.161 99 12 24 0 1365135840 11052352 2543312
00:51:59 77408 8192000000 10285454960 10739101696 1328486192 13.953 97 20 40 0 1357149584 24413296 4250096
00:54:36 56624 8192000000 10258663216 10710298624 1298470080 13.681 95 16 32 0 1313484176 11248272 3765824
00:56:37 9953 8192000000 10284326400 10716516352 1356734128 14.208 100 39 78 1 1380057568 14435696 8887744
00:57:50 116235 8192000000 10265057024 10715467776 1298649680 13.683 95 12 24 0 1317667360 16351488 2666192
01:00:49 60707 8192000000 10272292016 10712080384 1347011136 14.121 99 28 56 0 1362717616 8793456 6913024
01:03:08 32497 8192000000 10280557872 10718273536 1326142640 13.932 97 28 56 0 1340249952 7942864 6164448
01:04:42 49469 8192000000 10251394736 10715942912 1348357904 14.133 99 11 22 0 1360216496 9320624 2537968
01:06:33 63834 8192000000 10277917040 10734944256 1364764064 14.280 99 14 28 0 1380662496 12185744 3712688
01:08:37 35617 8192000000 10284753520 10742915072 1384422400 14.456 100 5 11 0 1392584288 7261440 900448
01:10:13 60718 8192000000 10305565840 10755948544 1387566768 14.484 99 23 46 1 1400354016 7008960 5778288
01:12:24 50259 8192000000 10298836800 10757685248 1392100832 14.525 99 4 8 0 1401672576 9075168 496576
01:14:17 72421 8192000000 10326279568 10789142528 1414250304 14.722 100 14 28 0 1436847920 19335136 3262480
01:16:30 45358 8192000000 10339870992 10796613632 1422895616 14.798 100 19 38 1 1441116832 13941456 4279760
01:18:18 32500 8192000000 10343453568 10795540480 1423050784 14.800 100 27 52 1 1447473424 18262240 6160400
01:19:53 91280 8192000000 10310512832 10771488768 1365038112 14.283 97 15 30 1 1390646960 22354096 3254752
01:22:36 45568 8192000000 10315493616 10757771264 1364839488 14.281 97 34 68 1 1384652720 12131200 7682032
01:24:25 8771 8192000000 10305877296 10738278400 1374082960 14.364 100 51 102 0 1396406064 11129808 11193296
01:25:36 122868 8192000000 10303373232 10751574016 1363868448 14.272 98 29 58 0 1384386176 13516256 7001472
01:28:54 29889 8192000000 10302970768 10767376384 1394928432 14.550 100 21 42 0 1413895568 14518784 4448352
01:30:24 43356 8192000000 10275394304 10739634176 1363734288 14.271 98 14 28 0 1377446896 10389920 3322688
01:32:19 49531 8192000000 10275579856 10734645248 1359719024 14.235 98 19 36 0 1370439232 6594128 4126080
01:34:19 100034 8192000000 10273392704 10731892736 1366252368 14.293 99 12 24 0 1381959776 12763728 2943680
01:37:09 68883 8192000000 10268071632 10728820736 1357908752 14.219 98 15 30 0 1378284192 16830032 3545408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-12-2022 01:40:26 to 11-12-2022 01:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 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 11-12-2022 01:40:42 to 11-12-2022 01:40:44.

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          238653
MON$OLDEST_ACTIVE               239725
MON$OLDEST_SNAPSHOT             239725
MON$NEXT_TRANSACTION            239725
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                       2045408
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.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Sun Dec 11 01:38:37 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Sun Dec 11 01:38:48 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Sun Dec 11 01:38:48 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Sun Dec 11 01:38:48 2022
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Sun Dec 11 01:38:48 2022
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:80: 
:81: 
:82: mscs-test-161.budzdorov.ru    Sun Dec 11 01:38:48 2022
:83:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:84: 
:85: 
:86: mscs-test-161.budzdorov.ru    Sun Dec 11 01:38:48 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Sun Dec 11 01:38:48 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:92: 
--- end of diff output ---

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

11.12.22 01:40:46. 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-12-11 01:40:48 No severe PSQL-related problems occured
11.12.22 01:40:50. End of report.