Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.03.2023 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 15326776 kB
MemAvailable 30420104 kB
Buffers 0 kB
Cached 16360108 kB
SwapCached 3032 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 2023-03-09 16:38:42
DTS_END                         2023-03-09 16:38:42.6530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54256
TRN_ID                          238439
ATT_ID                          624
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: 9290.14 23936 1114817
customer order: creation 168.35 351 20202
customer order: refuse 14.62 354 1754
order to supplier: creation 44.76 1239 5371
order to supplier: removal 3.63 105792 436
invoice (draft): creation 45.35 3848 5442
invoice (draft): removal 53.40 918 6408
invoice accept: apply 40.42 18703 4851
invoice accept: cancel 48.10 825 5772
customer reserve: creation 3828.62 181 459434
customer reserve: removal 4748.40 63 569808
realization accept: apply 83.46 193 10015
realization accept: cancel 84.19 107 10103
payment from customer: creation 58.10 123 6972
payment from customer: removal 5.68 77 682
payment to supplier: creation 47.56 149 5707
payment to supplier: removal 3.19 84 383
service: total inventory turnovers 4.88 1091 585
service: total monetary turnovers 6.73 98 807
service: aggregate perf. data 0.68 28194 81
service: refresh index statistics 0.03 316324 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-03-2023 16:39:50 to 09-03-2023 16:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 630
TEST_TIME 2 1862
TEST_TIME 3 2463
TEST_TIME 4 3727
TEST_TIME 5 4927
TEST_TIME 6 4862
TEST_TIME 7 5185
TEST_TIME 8 5399
TEST_TIME 9 5409
TEST_TIME 10 5539
TEST_TIME 11 5749
TEST_TIME 12 5701
TEST_TIME 13 6106
TEST_TIME 14 6155
TEST_TIME 15 6483
TEST_TIME 16 6826
TEST_TIME 17 6966
TEST_TIME 18 6887
TEST_TIME 19 6987
TEST_TIME 20 7349
TEST_TIME 21 7518
TEST_TIME 22 7531
TEST_TIME 23 7942
TEST_TIME 24 8117
TEST_TIME 25 8625
TEST_TIME 26 9113
TEST_TIME 27 9395
TEST_TIME 28 10234
TEST_TIME 29 10916
TEST_TIME 30 10867
TEST_TIME 31 10533
TEST_TIME 32 10352
TEST_TIME 33 10246
TEST_TIME 34 10329
TEST_TIME 35 10263
TEST_TIME 36 10152
TEST_TIME 37 9985
TEST_TIME 38 9981
TEST_TIME 39 9824
TEST_TIME 40 9778
TEST_TIME 41 9712
TEST_TIME 42 9572
TEST_TIME 43 9401
TEST_TIME 44 9508
TEST_TIME 45 9508
TEST_TIME 46 9480
TEST_TIME 47 9418
TEST_TIME 48 9365
TEST_TIME 49 9228
TEST_TIME 50 9187
TEST_TIME 51 9250
TEST_TIME 52 9319
TEST_TIME 53 9252
TEST_TIME 54 9224
TEST_TIME 55 9241
TEST_TIME 56 9431
TEST_TIME 57 9330
TEST_TIME 58 9433
TEST_TIME 59 9469
TEST_TIME 60 9518
TEST_TIME 61 9489
TEST_TIME 62 9653
TEST_TIME 63 9555
TEST_TIME 64 9560
TEST_TIME 65 9476
TEST_TIME 66 9423
TEST_TIME 67 9375
TEST_TIME 68 9377
TEST_TIME 69 9321
TEST_TIME 70 9293
TEST_TIME 71 9305
TEST_TIME 72 9278
TEST_TIME 73 9282
TEST_TIME 74 9243
TEST_TIME 75 9209
TEST_TIME 76 9234
TEST_TIME 77 9310
TEST_TIME 78 9308
TEST_TIME 79 9322
TEST_TIME 80 9296
TEST_TIME 81 9406
TEST_TIME 82 9405
TEST_TIME 83 9449
TEST_TIME 84 9455
TEST_TIME 85 9391
TEST_TIME 86 9329
TEST_TIME 87 9294
TEST_TIME 88 9269
TEST_TIME 89 9210
TEST_TIME 90 9219
TEST_TIME 91 9246
TEST_TIME 92 9252
TEST_TIME 93 9248
TEST_TIME 94 9213
TEST_TIME 95 9320
TEST_TIME 96 9362
TEST_TIME 97 9355
TEST_TIME 98 9340
TEST_TIME 99 9328
TEST_TIME 100 9374
TEST_TIME 101 9362
TEST_TIME 102 9475
TEST_TIME 103 9433
TEST_TIME 104 9470
TEST_TIME 105 9535
TEST_TIME 106 9467
TEST_TIME 107 9450
TEST_TIME 108 9406
TEST_TIME 109 9402
TEST_TIME 110 9386
TEST_TIME 111 9351
TEST_TIME 112 9315
TEST_TIME 113 9293
TEST_TIME 114 9307
TEST_TIME 115 9283
TEST_TIME 116 9288
TEST_TIME 117 9322
TEST_TIME 118 9306
TEST_TIME 119 9336
TEST_TIME 120 9290

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-03-2023 16:39:54 to 09-03-2023 16:39:58.

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 1540256 1540256 0 0.00 0 30621 6 0 0
doc_list_biud 1540256 1540256 0 0.00 0 48711 30 0 0
sp_add_invoice_to_stock 4899 4851 48 0.97 23 204705 18703 0 48
sp_cancel_adding_invoice 5772 5772 0 0.00 5 66904 825 0 0
sp_cancel_client_order 1754 1754 0 0.00 5 14372 354 0 0
sp_cancel_customer_reserve 569808 569808 0 0.00 0 48713 63 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 5546 78 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 2 4131 84 0 0
sp_cancel_supplier_invoice 6418 6408 10 0.15 1 26439 919 0 10
sp_cancel_supplier_order 436 436 0 0.00 6 1047963 105793 0 0
sp_cancel_write_off 10103 10103 0 0.00 1 9312 108 0 0
sp_client_order 20202 20202 0 0.00 3 32655 351 0 0
sp_customer_reserve 459434 459434 0 0.00 0 95837 181 0 0
sp_fill_shopping_cart 30986 30986 0 0.00 1 16678 41 0 0
sp_fill_shopping_cart_clo_res 459434 459434 0 0.00 0 30311 15 0 0
sp_get_clo_for_invoice 4851 4851 0 0.00 0 37483 341 0 0
sp_kill_cost_storno 16940 16940 0 0.00 0 5139 54 0 0
sp_kill_qstorno_ret_qs2qd 1156563 1156563 0 0.00 0 48706 35 0 0
sp_kill_qty_storno 609147 609147 0 0.00 0 48711 74 0 0
sp_lock_selected_doc 20576 20576 0 0.00 0 6081 5 0 0
sp_make_cost_storno 27545 27545 0 0.00 0 30611 58 0 0
sp_multiply_rows_for_pdistr 27545 27545 0 0.00 0 21027 28 0 0
sp_multiply_rows_for_qdistr 20202 20202 0 0.00 0 32387 172 0 0
sp_pay_from_customer 6972 6972 0 0.00 4 21044 124 0 0
sp_pay_to_supplier 5707 5707 0 0.00 4 45614 149 0 0
sp_payment_common 12679 12679 0 0.00 1 28840 43 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 14366 332 0 0
sp_qd_handle_on_invoice_upd_sts 10623 10623 0 0.00 0 33200 339 0 0
sp_qd_handle_on_reserve_upd_sts 10015 10015 0 0.00 0 17755 62 0 0
sp_reserve_write_off 10015 10015 0 0.00 6 19184 194 0 0
sp_supplier_invoice 5442 5442 0 0.00 5 129896 3849 0 0
sp_supplier_order 5371 5371 0 0.00 4 34055 1240 0 0
srv_aggregate_perf_data 82 81 1 1.21 2719 640198 28195 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 973 141641 45975 0 0
srv_find_qd_qs_mism 1067842 1067842 0 0.00 0 14268 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 40 53874 1091 0 0
srv_make_money_saldo 807 807 0 0.00 0 23492 98 0 0
srv_recalc_idx_stat 4 4 0 0.00 208067 431559 316324 0 0
t$perf-abend:sp_add_invoice_to_stock 48 48 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 4851 4851 0 0.00 0 11182 188 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 3755 101 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 4536 14 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 2415 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 823 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 3145 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 703 12 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 28247 1567 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 4673 9 0 0
t$perf-norm:sp_client_order 20202 20202 0 0.00 0 18619 20 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 1001 5 0 0
t$perf-norm:sp_pay_from_customer 6972 6972 0 0.00 0 5386 8 0 0
t$perf-norm:sp_pay_to_supplier 5707 5707 0 0.00 0 13262 12 0 0
t$perf-norm:sp_reserve_write_off 10015 10015 0 0.00 0 22159 18 0 0
t$perf-norm:sp_supplier_invoice 5442 5442 0 0.00 0 4826 17 0 0
t$perf-norm:sp_supplier_order 5371 5371 0 0.00 0 7484 15 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 505 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 1876 24 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 7765 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9798 9702 96 0.97 0 9786 18 0 96
v_all_customers 36714 36714 0 0.00 0 72 1 0 0
v_all_suppliers 30100 30100 0 0.00 0 21 1 0 0
v_all_wares 40404 40404 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 474 7 0 0
v_cancel_client_order 3508 3508 0 0.00 0 2439 5 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 5536 13 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 1024 6 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 1050 5 0 0
v_cancel_supplier_invoice 786 766 20 2.54 0 863 14 0 20
v_cancel_supplier_order 872 872 0 0.00 0 1098 6 0 0
v_cancel_write_off 1984 1984 0 0.00 0 1220 13 0 0
v_max_id_clo_ord 5371 5371 0 0.00 0 140 1 0 0
v_max_id_clo_res 989 989 0 0.00 0 2213 15 0 0
v_max_id_ord_sup 5409 5409 0 0.00 0 9012 9 0 0
v_max_non_paid_invoice 1437 1437 0 0.00 0 28817 22 0 0
v_max_non_paid_realizn 3710 3710 0 0.00 0 6700 7 0 0
v_min_id_clo_ord 5371 5371 0 0.00 0 5637 7 0 0
v_min_id_clo_res 989 989 0 0.00 0 8356 35 0 0
v_min_id_ord_sup 5409 5409 0 0.00 0 3181 6 0 0
v_min_non_paid_invoice 5707 5707 0 0.00 0 8899 32 0 0
v_min_non_paid_realizn 6972 6972 0 0.00 0 11831 30 0 0
v_our_firm 10214 10214 0 0.00 0 17 1 0 0
v_reserve_write_off 20030 20030 0 0.00 0 8567 8 0 0
x_lock_depdocs_on_canc_invoice 11797 11797 0 0.00 0 25805 128 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 9005 358 0 0
x_make_qstorno_1000_1200 5371 5371 0 0.00 1 34014 1165 0 0
x_make_qstorno_1200_2000 5442 5442 0 0.00 2 127224 3681 0 0
x_make_qstorno_2100_3300 459434 459434 0 0.00 0 95834 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-03-2023 16:39:59 to 09-03-2023 16:40:03.

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:46 7 8192000000 8808457920 8822824960 13219488 0.161 4 3 4 0 13404752 78160 107104
14:09:48 21534 8192000000 9903537728 10213257216 984601760 10.729 100 87 174 0 1013006528 5527456 22877312
14:11:18 18268 8192000000 9939047200 10262581248 1024467408 11.115 100 85 170 0 1054968384 8367936 22133040
14:12:38 13843 8192000000 9979105376 10310361088 1061004048 11.466 100 89 178 0 1093445504 9830320 22611136
14:14:07 12462 8192000000 10015259472 10364866560 1105437008 11.889 100 64 128 0 1130882112 10376864 15068240
14:15:22 9108 8192000000 10073690720 10431225856 1164183664 12.442 100 73 146 0 1192282320 11143200 16955456
14:16:37 3774 8192000000 10126095264 10493444096 1208115120 12.852 100 79 158 0 1238649760 12838624 17696016
14:17:44 1907 8192000000 10158872752 10535075840 1243666496 13.180 100 85 167 0 1275573280 13578512 18328272
14:18:48 2109 8192000000 10180664960 10564718592 1271189968 13.432 100 77 155 0 1301980448 14519776 16270704
14:19:51 919 8192000000 10206680080 10591928320 1294353408 13.644 100 90 179 1 1327069648 11532992 21183248
14:20:53 1344 8192000000 10200230256 10596810752 1297437088 13.672 100 65 129 0 1321788192 9418736 14932368
14:21:55 3899 8192000000 10198729456 10605150208 1306439312 13.754 100 52 102 2 1324768992 6905168 11424512
14:23:01 10837 8192000000 10191912736 10612170752 1314513088 13.827 100 12 24 0 1326375712 9386704 2475920
14:24:13 7103 8192000000 10205030656 10622038016 1319487680 13.872 100 14 29 0 1328517568 5384032 3645856
14:25:21 17119 8192000000 10230733648 10639400960 1325198528 13.924 100 60 119 0 1345754496 6073216 14482752
14:26:38 7408 8192000000 10225258832 10644316160 1333078912 13.995 100 28 56 1 1346131904 6755264 6297728
14:27:47 10995 8192000000 10229481072 10643824640 1328350192 13.952 100 20 40 1 1338457952 5432992 4674768
14:28:58 59239 8192000000 10166059600 10574041088 1216628208 12.930 92 17 34 0 1225471952 5112432 3731312
14:31:04 3317 8192000000 10165759776 10566676480 1258469376 13.316 98 27 52 0 1270683104 6179264 6034464
14:32:10 5727 8192000000 10123992912 10518089728 1190624576 12.689 95 23 47 0 1201093408 4943792 5525040
14:33:18 23911 8192000000 10154267696 10569912320 1260362496 13.333 99 26 53 1 1271902464 5192672 6347296
14:34:59 9444 8192000000 10167144768 10575855616 1277090608 13.486 100 23 46 0 1288436192 6423488 4922096
14:36:09 5623 8192000000 10182782432 10598690816 1298535904 13.682 100 31 60 1 1311476432 5559120 7381408
14:37:17 9602 8192000000 10199703360 10613886976 1308283088 13.770 100 17 35 0 1318376640 5660592 4432960
14:38:27 141641 8192000000 10235843696 10656952320 1337326736 14.033 100 41 80 1 1355506048 8127104 10052208
14:41:51 9603 8192000000 10275938464 10689904640 1362504272 14.260 100 57 112 0 1389612368 13977568 13130528
14:43:04 9935 8192000000 10285300032 10704289792 1376308464 14.384 100 64 128 1 1404880368 13982640 14589264
14:44:24 1530 8192000000 10308160816 10724462592 1390100096 14.507 100 64 125 0 1420177904 16232160 13845648
14:45:28 14619 8192000000 10316418208 10740297728 1398352432 14.580 100 46 89 0 1428609312 19620192 10636688
14:46:57 14836 8192000000 10318073216 10738675712 1398071552 14.578 100 59 118 0 1432211760 20170720 13969488
14:48:28 10794 8192000000 10349083696 10773422080 1419541696 14.769 100 56 111 0 1461892752 28926544 13424512
14:49:43 6045 8192000000 10359958576 10780356608 1411188048 14.694 99 55 108 0 1457037648 32292512 13557088
14:50:54 11038 8192000000 10367470848 10787876864 1416145744 14.739 100 53 106 2 1470049136 40591376 13312016
14:52:12 11213 8192000000 10358881568 10780725248 1411852544 14.700 100 64 128 0 1467140608 39582672 15705392
14:53:27 3776 8192000000 10370559456 10783555584 1401996816 14.613 99 80 158 1 1463427232 42240112 19190304
14:54:37 2043 8192000000 10354777936 10772086784 1392106544 14.525 99 70 140 0 1452110224 42702704 17300976
14:55:46 12542 8192000000 10350074864 10765557760 1396430752 14.563 100 55 110 0 1454562720 44263952 13868016
14:57:08 972 8192000000 10376558960 10784301056 1402080976 14.614 100 82 162 3 1476665360 54819904 19764480
14:58:21 1746 8192000000 10389216368 10792570880 1404185424 14.632 100 91 180 0 1487537584 59714864 23637296
14:59:25 6098 8192000000 10362495696 10775904256 1387757440 14.486 99 51 99 1 1457312864 56697728 12857696
15:00:37 8398 8192000000 10374272096 10789031936 1397923104 14.577 100 53 106 0 1479139280 66029472 15186704
15:01:50 11354 8192000000 10357474624 10782449664 1364692320 14.279 98 38 76 0 1443375952 67740048 10943584
15:03:04 8045 8192000000 10349466224 10771419136 1374158992 14.364 100 39 78 0 1451109248 65518496 11431760
15:04:31 23245 8192000000 10322450160 10758340608 1364041600 14.274 100 28 56 1 1430521504 57909568 8570336
15:05:58 32635 8192000000 10300100512 10737278976 1324729744 13.920 98 21 42 1 1390670768 59230864 6710160
15:07:34 18972 8192000000 10262920800 10717372416 1329147488 13.959 98 19 37 1 1361236192 26992608 5096096
15:08:55 71310 8192000000 10266559120 10699927552 1332430400 13.989 98 44 83 4 1346297104 4887888 8978816
15:11:22 41703 8192000000 10273504944 10711797760 1360101872 14.238 100 25 50 2 1375901792 10640480 5159440
15:13:16 70524 8192000000 10266301264 10722799616 1367848224 14.308 100 9 18 1 1382306400 12471712 1986464
15:15:27 114488 8192000000 10268854496 10723000320 1364130896 14.274 99 12 24 1 1374042512 7614448 2297168
15:18:23 32780 8192000000 10278390080 10730270720 1379620160 14.413 100 23 46 1 1393249936 8179408 5450368
15:19:57 117594 8192000000 10301187520 10755407872 1392335936 14.527 100 20 41 0 1407685904 10665808 4684160
15:23:00 50991 8192000000 10295085200 10735271936 1361727824 14.253 98 49 96 4 1382504960 9417760 11359376
15:24:53 15121 8192000000 10293461184 10748657664 1387877568 14.487 100 30 61 1 1405901584 11047984 6976032
15:26:09 58419 8192000000 10282133392 10741743616 1385675664 14.467 99 14 28 0 1398356288 9756192 2924432
15:28:08 64982 8192000000 10310390784 10781413376 1405262896 14.642 100 29 58 1 1429561200 16693456 7604848
15:30:14 69034 8192000000 10303496864 10759397376 1374536976 14.368 98 14 28 0 1393502032 15597456 3367600
15:32:29 25819 8192000000 10300553376 10749059072 1371225536 14.338 97 35 68 1 1393870320 14509840 8134944
15:34:03 58521 8192000000 10303030496 10773069824 1397325472 14.571 100 10 20 0 1417108512 17380928 2402112
15:36:02 69968 8192000000 10310405648 10765217792 1377624624 14.395 98 19 38 0 1393990352 11880352 4485376
15:38:13 97858 8192000000 10278836976 10733719552 1270012080 13.422 91 9 18 0 1280953728 8956144 1985504
15:41:20 42418 8192000000 10265724208 10708414464 1331697696 13.982 97 33 62 1 1351556784 12995776 6863312
15:43:03 94661 8192000000 10246580288 10681708544 1280285088 13.516 94 30 60 2 1293113408 6344976 6483344
15:45:48 37734 8192000000 10259330032 10703941632 1324224624 13.915 97 37 73 2 1344158320 11891552 8042144
15:47:27 33115 8192000000 10252237280 10702356480 1332824464 13.993 98 19 38 0 1353512592 16492752 4195376
15:49:09 61420 8192000000 10247616624 10707632128 1343038032 14.085 99 17 34 1 1361552112 14675088 3838992
15:51:13 54112 8192000000 10244348704 10692378624 1302002208 13.713 96 13 24 2 1313966800 9058720 2905872
15:53:07 42871 8192000000 10224855920 10668081152 1313777936 13.820 97 40 80 0 1332964464 9863728 9322800
15:54:50 66659 8192000000 10250425984 10706948096 1321636112 13.892 98 22 42 0 1338982032 12652336 4693584
15:57:00 68072 8192000000 10248402992 10696724480 1314875888 13.830 97 16 32 0 1331224912 12565952 3783072
15:59:09 81782 8192000000 10275897920 10728062976 1346887008 14.119 99 20 40 0 1364232400 13096304 4249088
16:01:35 47415 8192000000 10260167136 10703556608 1315824672 13.839 97 24 48 0 1332353024 10835360 5692992
16:03:23 16645 8192000000 10278421152 10707673088 1348621984 14.135 99 59 118 0 1370879584 9159792 13097808
16:04:48 84643 8192000000 10250109312 10697216000 1328889456 13.957 98 37 74 0 1347922016 11074192 7958368
16:07:14 70247 8192000000 10265069024 10726383616 1365580800 14.287 100 10 20 0 1382824048 14740144 2503104
16:09:42 62785 8192000000 10280614448 10739732480 1378716800 14.405 100 13 27 0 1391160240 9767504 2675936
16:12:12 69318 8192000000 10303245088 10760032256 1377368256 14.393 98 14 26 0 1394876656 14442496 3065904
16:14:21 52048 8192000000 10301715792 10762772480 1396284032 14.562 100 10 19 1 1414066624 15198128 2584464
16:16:18 40765 8192000000 10305351360 10745585664 1374143968 14.364 99 25 48 1 1388644992 9272752 5228272
16:18:01 40491 8192000000 10300684144 10766901248 1395961152 14.559 100 31 62 0 1415791088 12991728 6838208
16:19:48 89499 8192000000 10308719872 10764648448 1370442672 14.331 99 12 24 0 1396981632 23843456 2695504
16:22:19 96028 8192000000 10300890480 10769887232 1337619744 14.036 96 21 40 0 1359553664 17726720 4207200
16:24:56 77695 8192000000 10274083632 10727366656 1361865520 14.254 98 18 36 1 1378135120 11325680 4943920
16:27:25 42201 8192000000 10261092144 10714845184 1324462560 13.917 96 17 34 0 1336669664 8628496 3578608
16:29:20 47577 8192000000 10265056816 10726813696 1367767136 14.307 100 11 22 1 1379762816 9236128 2759552
16:31:09 68857 8192000000 10272920080 10734239744 1376761456 14.388 100 20 40 0 1396163936 14469984 4932496
16:33:47 53506 8192000000 10262808992 10715525120 1335568608 14.017 97 13 26 0 1350441328 11891136 2981584
16:35:40 60174 8192000000 10274223184 10727231488 1324768896 13.920 96 10 20 0 1336786336 9719696 2297744
16:37:43 65338 8192000000 9476062784 9793826816 608570832 6.915 44 13 26 0 619719360 8241024 2907504

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-03-2023 16:40:05 to 09-03-2023 16:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 48 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-03-2023 16:40:21 to 09-03-2023 16:40:23.

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          236062
MON$OLDEST_ACTIVE               238706
MON$OLDEST_SNAPSHOT             238706
MON$NEXT_TRANSACTION            238706
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                       2023552
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:1230: 
:1231: mscs-test-161.budzdorov.ru    Thu Mar  9 16:38:50 2023
:1232:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1233: 
:1234: 
:1235: mscs-test-161.budzdorov.ru    Thu Mar  9 16:38:51 2023
:1236:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1237: 
--- end of diff output ---

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

09.03.23 16:40:24. 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
2023-03-09 16:40:27 No severe PSQL-related problems occured
09.03.23 16:40:28. End of report.