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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14947244 kB
MemAvailable 30092224 kB
Buffers 0 kB
Cached 16739804 kB
SwapCached 3260 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-08 16:38:32
DTS_END                         2022-12-08 16:38:32.7780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44476
TRN_ID                          236145
ATT_ID                          710
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: 9139.25 27933 1096710
customer order: creation 162.51 401 19501
customer order: refuse 14.13 338 1696
order to supplier: creation 43.43 1281 5212
order to supplier: removal 3.38 113472 405
invoice (draft): creation 44.87 3893 5384
invoice (draft): removal 54.33 794 6519
invoice accept: apply 39.67 16988 4761
invoice accept: cancel 48.84 811 5861
customer reserve: creation 3678.88 166 441465
customer reserve: removal 4762.04 63 571445
realization accept: apply 79.29 190 9515
realization accept: cancel 84.03 107 10084
payment from customer: creation 56.94 119 6833
payment from customer: removal 5.52 80 662
payment to supplier: creation 46.65 147 5598
payment to supplier: removal 3.10 113 372
service: total inventory turnovers 4.18 1558 501
service: total monetary turnovers 6.58 25 789
service: aggregate perf. data 0.86 21376 103
service: refresh index statistics 0.03 396733 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-12-2022 16:39:17 to 08-12-2022 16:39:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4430
TEST_TIME 2 3344
TEST_TIME 3 3668
TEST_TIME 4 4223
TEST_TIME 5 4408
TEST_TIME 6 4423
TEST_TIME 7 4557
TEST_TIME 8 4722
TEST_TIME 9 5105
TEST_TIME 10 5309
TEST_TIME 11 5872
TEST_TIME 12 6266
TEST_TIME 13 6416
TEST_TIME 14 6597
TEST_TIME 15 6779
TEST_TIME 16 7191
TEST_TIME 17 7765
TEST_TIME 18 7769
TEST_TIME 19 7677
TEST_TIME 20 7579
TEST_TIME 21 7938
TEST_TIME 22 8512
TEST_TIME 23 8829
TEST_TIME 24 8653
TEST_TIME 25 9337
TEST_TIME 26 9355
TEST_TIME 27 9495
TEST_TIME 28 9931
TEST_TIME 29 9809
TEST_TIME 30 10385
TEST_TIME 31 10526
TEST_TIME 32 10573
TEST_TIME 33 10501
TEST_TIME 34 10377
TEST_TIME 35 10328
TEST_TIME 36 10220
TEST_TIME 37 10051
TEST_TIME 38 9915
TEST_TIME 39 9760
TEST_TIME 40 9649
TEST_TIME 41 9653
TEST_TIME 42 9532
TEST_TIME 43 9492
TEST_TIME 44 9481
TEST_TIME 45 9485
TEST_TIME 46 9392
TEST_TIME 47 9329
TEST_TIME 48 9382
TEST_TIME 49 9341
TEST_TIME 50 9234
TEST_TIME 51 9266
TEST_TIME 52 9194
TEST_TIME 53 9125
TEST_TIME 54 9041
TEST_TIME 55 8967
TEST_TIME 56 8912
TEST_TIME 57 8910
TEST_TIME 58 8987
TEST_TIME 59 8885
TEST_TIME 60 9054
TEST_TIME 61 9112
TEST_TIME 62 9102
TEST_TIME 63 9084
TEST_TIME 64 9168
TEST_TIME 65 9204
TEST_TIME 66 9233
TEST_TIME 67 9269
TEST_TIME 68 9225
TEST_TIME 69 9201
TEST_TIME 70 9310
TEST_TIME 71 9346
TEST_TIME 72 9325
TEST_TIME 73 9393
TEST_TIME 74 9399
TEST_TIME 75 9292
TEST_TIME 76 9205
TEST_TIME 77 9233
TEST_TIME 78 9213
TEST_TIME 79 9151
TEST_TIME 80 9113
TEST_TIME 81 9082
TEST_TIME 82 9020
TEST_TIME 83 9037
TEST_TIME 84 9083
TEST_TIME 85 9118
TEST_TIME 86 9096
TEST_TIME 87 9087
TEST_TIME 88 9134
TEST_TIME 89 9140
TEST_TIME 90 9137
TEST_TIME 91 9159
TEST_TIME 92 9185
TEST_TIME 93 9189
TEST_TIME 94 9178
TEST_TIME 95 9298
TEST_TIME 96 9324
TEST_TIME 97 9309
TEST_TIME 98 9226
TEST_TIME 99 9279
TEST_TIME 100 9225
TEST_TIME 101 9207
TEST_TIME 102 9167
TEST_TIME 103 9134
TEST_TIME 104 9095
TEST_TIME 105 9047
TEST_TIME 106 9012
TEST_TIME 107 9043
TEST_TIME 108 9012
TEST_TIME 109 8982
TEST_TIME 110 8976
TEST_TIME 111 9010
TEST_TIME 112 9057
TEST_TIME 113 9049
TEST_TIME 114 9045
TEST_TIME 115 9064
TEST_TIME 116 9034
TEST_TIME 117 9085
TEST_TIME 118 9091
TEST_TIME 119 9130
TEST_TIME 120 9145

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-12-2022 16:39:21 to 08-12-2022 16:39:24.

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 1505767 1505767 0 0.00 0 37196 6 0 0
doc_list_biud 1505764 1505764 0 0.00 0 56735 30 0 0
sp_add_invoice_to_stock 4830 4761 69 1.42 34 139857 16989 0 69
sp_cancel_adding_invoice 5861 5861 0 0.00 5 52081 811 0 0
sp_cancel_client_order 1696 1696 0 0.00 4 9638 339 0 0
sp_cancel_customer_reserve 571445 571445 0 0.00 0 56738 64 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 9122 80 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 3 8319 114 0 0
sp_cancel_supplier_invoice 6524 6519 5 0.07 2 42750 795 0 5
sp_cancel_supplier_order 405 405 0 0.00 9 1008367 113473 0 0
sp_cancel_write_off 10084 10084 0 0.00 1 14977 107 0 0
sp_client_order 19501 19501 0 0.00 3 34839 402 0 0
sp_customer_reserve 441465 441465 0 0.00 0 46768 166 0 0
sp_fill_shopping_cart 30096 30096 0 0.00 1 25144 49 0 0
sp_fill_shopping_cart_clo_res 441465 441465 0 0.00 0 16914 15 0 0
sp_get_clo_for_invoice 4746 4746 0 0.00 0 35339 336 0 0
sp_kill_cost_storno 16979 16979 0 0.00 0 37187 61 0 0
sp_kill_qstorno_ret_qs2qd 1159898 1159898 0 0.00 0 54926 34 0 0
sp_kill_qty_storno 610269 610269 0 0.00 0 56735 73 0 0
sp_lock_selected_doc 19842 19842 0 0.00 0 9112 5 0 0
sp_make_cost_storno 26692 26692 0 0.00 0 23622 62 0 0
sp_multiply_rows_for_pdistr 26692 26692 0 0.00 0 28224 33 0 0
sp_multiply_rows_for_qdistr 19501 19501 0 0.00 0 9407 167 0 0
sp_pay_from_customer 6833 6833 0 0.00 4 28244 120 0 0
sp_pay_to_supplier 5598 5598 0 0.00 4 18685 148 0 0
sp_payment_common 12430 12430 0 0.00 1 15283 33 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 0 9631 313 0 0
sp_qd_handle_on_invoice_upd_sts 10607 10607 0 0.00 0 17506 347 0 0
sp_qd_handle_on_reserve_upd_sts 9513 9513 0 0.00 0 7991 56 0 0
sp_reserve_write_off 9515 9515 0 0.00 6 16971 190 0 0
sp_supplier_invoice 5384 5384 0 0.00 6 120627 3893 0 0
sp_supplier_order 5212 5212 0 0.00 4 22309 1282 0 0
srv_aggregate_perf_data 103 103 0 0.00 3264 370259 21377 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2176 141988 44948 0 0
srv_find_qd_qs_mism 1051285 1051285 0 0.00 0 18878 4 0 0
srv_make_invnt_saldo 501 501 0 0.00 35 131784 1559 0 0
srv_make_money_saldo 789 789 0 0.00 0 412 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 163681 505973 396733 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4761 4761 0 0.00 0 20507 182 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 13295 156 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 7843 21 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 7681 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 693 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 4056 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 548 14 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 16018 1605 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 945 8 0 0
t$perf-norm:sp_client_order 19501 19501 0 0.00 0 11768 21 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 7708 17 0 0
t$perf-norm:sp_pay_from_customer 6833 6833 0 0.00 0 7806 12 0 0
t$perf-norm:sp_pay_to_supplier 5598 5598 0 0.00 0 11487 20 0 0
t$perf-norm:sp_reserve_write_off 9515 9515 0 0.00 0 16029 16 0 0
t$perf-norm:sp_supplier_invoice 5384 5384 0 0.00 0 9906 23 0 0
t$perf-norm:sp_supplier_order 5212 5212 0 0.00 0 17914 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 355 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 7534 53 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 930 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9622 9484 138 1.43 0 5289 17 0 138
v_all_customers 35888 35888 0 0.00 0 27 1 0 0
v_all_suppliers 29458 29458 0 0.00 0 199 1 0 0
v_all_wares 39002 39002 0 0.00 0 185 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 226 7 0 0
v_cancel_client_order 3392 3392 0 0.00 0 1077 4 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 920 5 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 878 5 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 865 4 0 0
v_cancel_supplier_invoice 778 768 10 1.28 0 4275 35 0 10
v_cancel_supplier_order 810 810 0 0.00 0 1074 6 0 0
v_cancel_write_off 2028 2028 0 0.00 0 2885 16 0 0
v_max_id_clo_ord 5212 5212 0 0.00 0 4604 4 0 0
v_max_id_clo_res 968 968 0 0.00 0 2923 17 0 0
v_max_id_ord_sup 5383 5383 0 0.00 0 4468 5 0 0
v_max_non_paid_invoice 1464 1464 0 0.00 0 994 4 0 0
v_max_non_paid_realizn 3583 3583 0 0.00 0 3871 4 0 0
v_min_id_clo_ord 5212 5212 0 0.00 0 5546 10 0 0
v_min_id_clo_res 968 968 0 0.00 0 3696 9 0 0
v_min_id_ord_sup 5383 5383 0 0.00 0 5717 10 0 0
v_min_non_paid_invoice 5598 5598 0 0.00 0 8204 21 0 0
v_min_non_paid_realizn 6832 6832 0 0.00 0 15273 27 0 0
v_our_firm 9612 9612 0 0.00 0 17 1 0 0
v_reserve_write_off 19024 19024 0 0.00 0 14210 9 0 0
x_lock_depdocs_on_canc_invoice 11996 11996 0 0.00 0 10636 128 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 8521 347 0 0
x_make_qstorno_1000_1200 5212 5212 0 0.00 1 22301 1190 0 0
x_make_qstorno_1200_2000 5384 5384 0 0.00 2 120569 3721 0 0
x_make_qstorno_2100_3300 441465 441465 0 0.00 0 46767 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-12-2022 16:39:25 to 08-12-2022 16:39:29.

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:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:37 24996 8192000000 9906613104 10218024960 985224944 10.735 100 90 180 0 1015518160 6001120 24292096
14:11:07 21518 8192000000 9948405056 10270846976 1031943952 11.187 100 86 173 0 1061885024 8429840 21511232
14:12:32 18785 8192000000 9976863376 10310385664 1062119696 11.477 100 88 176 0 1094615168 10812992 21682480
14:13:58 7530 8192000000 10032232640 10380570624 1120939024 12.036 100 80 160 0 1151420592 11489440 18992128
14:15:08 4241 8192000000 10086824688 10443763712 1173687664 12.531 100 86 169 1 1206321072 13325376 19308032
14:16:18 3285 8192000000 10115506160 10484490240 1204378240 12.817 100 81 162 0 1236253120 13832640 18042240
14:17:24 1798 8192000000 10153114112 10526420992 1238889952 13.136 100 84 168 0 1270466384 13429824 18146608
14:18:27 1880 8192000000 10169386928 10550865920 1259566704 13.326 100 92 181 2 1293506848 14019712 19920432
14:19:30 692 8192000000 10202833728 10592317440 1295650736 13.656 100 82 160 1 1325098736 12272736 17175264
14:20:33 1912 8192000000 10215631072 10606313472 1306642000 13.756 100 87 170 1 1337457968 12066000 18749968
14:21:35 7962 8192000000 10190794496 10608902144 1309236512 13.779 100 28 54 0 1321516224 6206352 6073360
14:22:45 13443 8192000000 10208382672 10626715648 1319999680 13.877 100 20 41 0 1330702000 5791840 4910480
14:23:59 9288 8192000000 10216642784 10637434880 1331468656 13.980 100 16 31 1 1340779552 5946960 3363936
14:25:10 51203 8192000000 10232391504 10654420992 1336092320 14.022 100 50 99 0 1353319968 5546032 11681616
14:27:02 6459 8192000000 10235652272 10658799616 1339749344 14.055 100 15 30 1 1348198240 5114096 3334800
14:28:09 8203 8192000000 10226592864 10643800064 1327406608 13.944 100 20 41 1 1341436368 10047824 3981936
14:29:18 62123 8192000000 10166062528 10578993152 1247140192 13.212 95 38 77 0 1260682064 4556400 8985472
14:31:24 5643 8192000000 10149795792 10554978304 1216425920 12.929 95 27 53 1 1228049792 5484704 6139168
14:32:32 7537 8192000000 10112806224 10509262848 1202634624 12.801 97 20 39 1 1211614640 4682848 4297168
14:33:40 61358 8192000000 10159795696 10561949696 1253882848 13.274 99 32 64 0 1265873856 5147312 6843696
14:35:52 3894 8192000000 10185953584 10596274176 1299114704 13.687 100 24 48 0 1310283456 5990208 5178544
14:36:59 8892 8192000000 10193154784 10607108096 1303498240 13.727 100 16 30 0 1312289584 5536816 3254528
14:38:08 18624 8192000000 10209676416 10627772416 1320681024 13.883 100 14 27 0 1328629888 4707888 3240976
14:39:29 51240 8192000000 10229988384 10647461888 1334164096 14.005 100 39 78 0 1349446304 6115712 9166496
14:41:29 10385 8192000000 10257906944 10675982336 1353300208 14.177 100 34 69 0 1371433184 9999424 8133552
14:42:44 27694 8192000000 10273722224 10692960256 1369688912 14.324 100 40 76 1 1387893440 9288976 8915552
14:44:29 7334 8192000000 10292852368 10711113728 1381628080 14.431 100 49 98 1 1405021472 11995520 11397872
14:45:42 12192 8192000000 10310211568 10736816128 1398878336 14.585 100 43 85 0 1428157200 18092976 11185888
14:46:59 6311 8192000000 10316261776 10739675136 1394329024 14.544 100 48 94 0 1425754976 20138624 11287328
14:48:09 6139 8192000000 10314753136 10729648128 1381385584 14.429 99 66 131 1 1422146720 23962848 16798288
14:49:22 2175 8192000000 10323740336 10742878208 1392919392 14.532 100 85 164 0 1438388512 25101072 20368048
14:50:31 10690 8192000000 10325757648 10747367424 1392010112 14.524 100 56 110 0 1436167872 30683504 13474256
14:51:52 4045 8192000000 10338472464 10760613888 1405446352 14.643 100 57 114 0 1451376496 31754912 14175232
14:53:02 6487 8192000000 10367807776 10787442688 1414818928 14.727 100 68 136 0 1470446448 38555072 17072448
14:54:17 2850 8192000000 10348295072 10759794688 1392341344 14.527 100 74 147 6 1450076400 38335296 19399760
14:55:25 6836 8192000000 10332578528 10750808064 1384461024 14.456 100 56 112 1 1434655920 36168912 14025984
14:56:34 12982 8192000000 10306306688 10733150208 1373125968 14.355 100 42 84 0 1420419792 35852272 11441552
14:57:55 5902 8192000000 10304565312 10718744576 1362782256 14.262 100 57 114 0 1412638576 35704400 14151920
14:59:07 13301 8192000000 10322798080 10749800448 1365549744 14.287 99 36 68 0 1425506992 49839040 10118208
15:00:27 12164 8192000000 10331004704 10751078400 1378345360 14.402 100 46 90 1 1437645056 46168816 13130880
15:01:50 5498 8192000000 10341642736 10751516672 1384676784 14.458 100 65 126 1 1435382720 33743520 16962416
15:03:01 3360 8192000000 10352767008 10756775936 1376575664 14.386 99 91 178 0 1449980000 50788272 22616064
15:04:06 11178 8192000000 10338415056 10775941120 1393688624 14.539 100 37 72 1 1445693824 41370496 10634704
15:05:44 16538 8192000000 10315297984 10752143360 1363733360 14.271 99 25 50 0 1416029040 45171760 7123920
15:07:04 49530 8192000000 10294633504 10732224512 1345288992 14.105 99 31 61 0 1393242080 40122608 7830480
15:08:57 25976 8192000000 10297272736 10738577408 1376547104 14.386 100 18 36 0 1409040608 28163840 4329664
15:10:24 18122 8192000000 10286810176 10732482560 1360160960 14.239 99 32 61 0 1386670352 19303280 7206112
15:12:05 34651 8192000000 10272094880 10720399360 1363720752 14.271 99 22 45 0 1376860048 8259840 4879456
15:13:46 104002 8192000000 10282159072 10735050752 1357323888 14.213 98 6 12 0 1368211728 9729920 1157920
15:16:58 79520 8192000000 10304828400 10754359296 1393494848 14.537 100 38 74 0 1413084592 11145552 8444192
15:19:30 36167 8192000000 10313885088 10768379904 1399127728 14.587 100 33 66 0 1421030624 14127072 7775824
15:22:13 41787 8192000000 10309854096 10759426048 1395290544 14.553 100 34 68 1 1426010880 21986688 8733648
15:24:02 38298 8192000000 10303589264 10756423680 1385958336 14.470 99 9 18 1 1400311312 12349328 2003648
15:25:41 30635 8192000000 10297498368 10757189632 1389463328 14.501 99 18 36 0 1408121104 14227744 4430032
15:28:15 141988 8192000000 10306905584 10769649664 1403154528 14.623 100 25 50 0 1427872064 18785120 5932416
15:31:37 69085 8192000000 10306575904 10762633216 1397619456 14.574 99 19 38 0 1414562656 12807456 4135744
15:33:49 44887 8192000000 10324033792 10786402304 1411435104 14.697 99 27 54 0 1437344656 19114384 6795168
15:35:35 51484 8192000000 10315173296 10781741056 1390602480 14.511 98 14 28 0 1419183632 24990880 3590272
15:37:29 69050 8192000000 10296509552 10774646784 1389432320 14.501 99 11 24 0 1408742064 16432592 2877152
15:39:40 81853 8192000000 10289985072 10749263872 1348783488 14.137 97 11 22 0 1360710176 9655840 2270848
15:42:02 51070 8192000000 10273980144 10732412928 1346640944 14.117 97 20 40 0 1360650928 9463856 4546128
15:43:59 36038 8192000000 10303848720 10760175616 1379556960 14.413 99 21 42 1 1398973920 15028960 4388000
15:45:41 24564 8192000000 10291350496 10728890368 1338644432 14.045 97 13 25 0 1350289840 9069296 2576112
15:47:07 58848 8192000000 10293189760 10745049088 1357730448 14.217 98 30 60 0 1382500336 17966784 6803104
15:49:07 29527 8192000000 10289021216 10729205760 1363633248 14.270 99 29 59 1 1377695552 7329328 6732976
15:50:44 41201 8192000000 10279271040 10733285376 1360633472 14.243 99 28 56 0 1380632304 13716592 6282240
15:52:56 141682 8192000000 10259384080 10702548992 1352943376 14.174 100 23 46 1 1372965808 14915520 5106912
15:56:18 59395 8192000000 10244200400 10697703424 1319580624 13.873 97 15 30 0 1336577952 13852096 3145232
15:58:20 77122 8192000000 10266767920 10726912000 1363154736 14.266 100 12 24 0 1378729296 12595984 2978576
16:01:05 53300 8192000000 10280980752 10735964160 1356515984 14.206 99 27 54 1 1379840128 16565040 6759104
16:03:01 69330 8192000000 10266201088 10714443776 1332515520 13.990 97 13 24 0 1346930832 11828256 2587056
16:05:14 29989 8192000000 10289629344 10721411072 1359038096 14.229 99 49 94 1 1379182784 8992832 11151856
16:06:45 116247 8192000000 10280098640 10729766912 1329813584 13.965 96 14 27 0 1342153184 9742656 2596944
16:09:43 72805 8192000000 10288008304 10745782272 1382727792 14.441 100 26 52 0 1403470816 15137952 5605072
16:11:56 87286 8192000000 10302704688 10765066240 1364908528 14.281 98 13 24 0 1384496720 16841504 2746688
16:14:29 105112 8192000000 10306431360 10749947904 1384674448 14.458 99 18 36 0 1404277888 15430800 4172640
16:17:27 49912 8192000000 10295052512 10757783552 1395075536 14.551 100 19 38 0 1410541728 10888416 4577776
16:19:17 61433 8192000000 10293670016 10757820416 1390772304 14.513 99 9 18 0 1398343232 5459760 2111168
16:21:45 38277 8192000000 10313622720 10771767296 1410349616 14.687 100 19 39 1 1421701376 7020848 4330912
16:23:38 91744 8192000000 10287369920 10738679808 1331145504 13.978 94 20 41 1 1350240304 14278640 4816160
16:26:09 50686 8192000000 10295032384 10751279104 1354302912 14.186 97 16 30 0 1371040128 13479824 3257392
16:28:06 86413 8192000000 10305257472 10767347712 1372175152 14.347 98 25 51 0 1391393712 13387328 5831232
16:30:39 51834 8192000000 10264144192 10716581888 1302675760 13.720 94 9 18 0 1318875776 14304256 1895760
16:32:31 40955 8192000000 10258081008 10709184512 1327604144 13.946 97 21 42 0 1342341968 9815376 4922448
16:34:13 26933 8192000000 10276888960 10725072896 1359783744 14.235 100 26 52 0 1376161520 10842624 5535152
16:35:43 127537 8192000000 10285235504 10745593856 1348605952 14.135 98 14 28 0 1364181072 11979888 3595232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-12-2022 16:39:31 to 08-12-2022 16:39:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-12-2022 16:39:47 to 08-12-2022 16:39:49.

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          235492
MON$OLDEST_ACTIVE               236352
MON$OLDEST_SNAPSHOT             236352
MON$NEXT_TRANSACTION            236352
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                       2043872
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.33646 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33646 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33646 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33646 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 ---
:134: 
:135: mscs-test-161.budzdorov.ru    Thu Dec  8 16:38:33 2022
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:137: 
:138: 
:139: mscs-test-161.budzdorov.ru    Thu Dec  8 16:38:36 2022
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:141: 
:142: 
:143: mscs-test-161.budzdorov.ru    Thu Dec  8 16:38:44 2022
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:145: 
--- end of diff output ---

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

08.12.22 16:39:50. 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-08 16:39:53 No severe PSQL-related problems occured
08.12.22 16:39:54. End of report.