Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.02.2023 22: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 15202324 kB
MemAvailable 30427064 kB
Buffers 0 kB
Cached 16487800 kB
SwapCached 3160 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-02-27 22:38:39
DTS_END                         2023-02-27 22:38:39.9070
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/46070
TRN_ID                          252014
ATT_ID                          736
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: 9381.65 18216 1125798
customer order: creation 189.16 322 22699
customer order: refuse 16.23 345 1948
order to supplier: creation 48.78 1159 5854
order to supplier: removal 3.53 86382 424
invoice (draft): creation 50.11 3081 6013
invoice (draft): removal 51.71 724 6205
invoice accept: apply 45.35 17513 5442
invoice accept: cancel 46.35 780 5562
customer reserve: creation 4125.52 178 495062
customer reserve: removal 4491.30 54 538956
realization accept: apply 90.39 168 10847
realization accept: cancel 84.04 101 10085
payment from customer: creation 63.34 114 7601
payment from customer: removal 6.20 81 744
payment to supplier: creation 52.97 126 6356
payment to supplier: removal 3.76 98 451
service: total inventory turnovers 4.97 1135 596
service: total monetary turnovers 7.07 43 848
service: aggregate perf. data 0.84 21230 101
service: refresh index statistics 0.03 230678 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-02-2023 22:39:44 to 27-02-2023 22:39:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7337
TEST_TIME 2 4741
TEST_TIME 3 5144
TEST_TIME 4 5990
TEST_TIME 5 5761
TEST_TIME 6 5853
TEST_TIME 7 5710
TEST_TIME 8 5748
TEST_TIME 9 5677
TEST_TIME 10 5609
TEST_TIME 11 5855
TEST_TIME 12 6075
TEST_TIME 13 6015
TEST_TIME 14 5935
TEST_TIME 15 6425
TEST_TIME 16 6578
TEST_TIME 17 6376
TEST_TIME 18 6798
TEST_TIME 19 6800
TEST_TIME 20 7051
TEST_TIME 21 7009
TEST_TIME 22 7125
TEST_TIME 23 7935
TEST_TIME 24 8229
TEST_TIME 25 8522
TEST_TIME 26 8761
TEST_TIME 27 9062
TEST_TIME 28 9178
TEST_TIME 29 9159
TEST_TIME 30 9798
TEST_TIME 31 10278
TEST_TIME 32 10070
TEST_TIME 33 10229
TEST_TIME 34 10281
TEST_TIME 35 10282
TEST_TIME 36 10193
TEST_TIME 37 10029
TEST_TIME 38 10117
TEST_TIME 39 10042
TEST_TIME 40 10054
TEST_TIME 41 10043
TEST_TIME 42 10057
TEST_TIME 43 9882
TEST_TIME 44 9792
TEST_TIME 45 9817
TEST_TIME 46 9723
TEST_TIME 47 9631
TEST_TIME 48 9678
TEST_TIME 49 9709
TEST_TIME 50 9713
TEST_TIME 51 9852
TEST_TIME 52 9749
TEST_TIME 53 9699
TEST_TIME 54 9697
TEST_TIME 55 9653
TEST_TIME 56 9557
TEST_TIME 57 9546
TEST_TIME 58 9459
TEST_TIME 59 9414
TEST_TIME 60 9422
TEST_TIME 61 9360
TEST_TIME 62 9337
TEST_TIME 63 9486
TEST_TIME 64 9506
TEST_TIME 65 9513
TEST_TIME 66 9569
TEST_TIME 67 9589
TEST_TIME 68 9632
TEST_TIME 69 9704
TEST_TIME 70 9671
TEST_TIME 71 9693
TEST_TIME 72 9767
TEST_TIME 73 9750
TEST_TIME 74 9782
TEST_TIME 75 9744
TEST_TIME 76 9628
TEST_TIME 77 9618
TEST_TIME 78 9604
TEST_TIME 79 9556
TEST_TIME 80 9621
TEST_TIME 81 9575
TEST_TIME 82 9535
TEST_TIME 83 9490
TEST_TIME 84 9480
TEST_TIME 85 9438
TEST_TIME 86 9417
TEST_TIME 87 9436
TEST_TIME 88 9390
TEST_TIME 89 9451
TEST_TIME 90 9445
TEST_TIME 91 9424
TEST_TIME 92 9485
TEST_TIME 93 9498
TEST_TIME 94 9530
TEST_TIME 95 9510
TEST_TIME 96 9555
TEST_TIME 97 9533
TEST_TIME 98 9576
TEST_TIME 99 9584
TEST_TIME 100 9562
TEST_TIME 101 9611
TEST_TIME 102 9602
TEST_TIME 103 9517
TEST_TIME 104 9524
TEST_TIME 105 9459
TEST_TIME 106 9496
TEST_TIME 107 9467
TEST_TIME 108 9429
TEST_TIME 109 9417
TEST_TIME 110 9375
TEST_TIME 111 9323
TEST_TIME 112 9311
TEST_TIME 113 9325
TEST_TIME 114 9307
TEST_TIME 115 9315
TEST_TIME 116 9305
TEST_TIME 117 9312
TEST_TIME 118 9333
TEST_TIME 119 9348
TEST_TIME 120 9386

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-02-2023 22:39:47 to 27-02-2023 22:39:51.

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 1584161 1584161 0 0.00 0 18422 6 0 0
doc_list_biud 1584159 1584159 0 0.00 0 30126 24 0 0
sp_add_invoice_to_stock 5532 5442 90 1.62 36 210385 17513 0 90
sp_cancel_adding_invoice 5562 5562 0 0.00 5 50298 780 0 0
sp_cancel_client_order 1948 1948 0 0.00 3 8537 346 0 0
sp_cancel_customer_reserve 538956 538956 0 0.00 0 30128 54 0 0
sp_cancel_pay_from_customer 744 744 0 0.00 2 6673 81 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 2 11950 98 0 0
sp_cancel_supplier_invoice 6215 6205 10 0.16 2 22529 724 0 10
sp_cancel_supplier_order 424 424 0 0.00 4 1048603 86382 0 0
sp_cancel_write_off 10085 10085 0 0.00 1 12071 102 0 0
sp_client_order 22699 22699 0 0.00 3 22557 323 0 0
sp_customer_reserve 495062 495062 0 0.00 0 51416 178 0 0
sp_fill_shopping_cart 34561 34561 0 0.00 1 13018 34 0 0
sp_fill_shopping_cart_clo_res 495057 495057 0 0.00 0 19210 13 0 0
sp_get_clo_for_invoice 5434 5434 0 0.00 0 26648 251 0 0
sp_kill_cost_storno 16842 16842 0 0.00 0 13043 51 0 0
sp_kill_qstorno_ret_qs2qd 1094626 1094626 0 0.00 0 30081 30 0 0
sp_kill_qty_storno 579461 579461 0 0.00 0 30126 64 0 0
sp_lock_selected_doc 22636 22636 0 0.00 0 7928 5 0 0
sp_make_cost_storno 30238 30238 0 0.00 0 18416 53 0 0
sp_multiply_rows_for_pdistr 30238 30238 0 0.00 0 12905 25 0 0
sp_multiply_rows_for_qdistr 22698 22698 0 0.00 0 22517 152 0 0
sp_pay_from_customer 7601 7601 0 0.00 4 13451 114 0 0
sp_pay_to_supplier 6356 6356 0 0.00 5 18640 127 0 0
sp_payment_common 13955 13955 0 0.00 1 18600 30 0 0
sp_qd_handle_on_cancel_clo 1948 1948 0 0.00 0 8510 309 0 0
sp_qd_handle_on_invoice_upd_sts 10996 10996 0 0.00 0 16810 295 0 0
sp_qd_handle_on_reserve_upd_sts 10847 10847 0 0.00 0 14518 59 0 0
sp_reserve_write_off 10847 10847 0 0.00 5 18431 169 0 0
sp_supplier_invoice 6013 6013 0 0.00 6 79312 3081 0 0
sp_supplier_order 5854 5854 0 0.00 5 23378 1159 0 0
srv_aggregate_perf_data 101 101 0 0.00 2498 201297 21231 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 911 108837 40296 0 0
srv_find_qd_qs_mism 1074238 1074238 0 0.00 0 11365 6 0 0
srv_make_invnt_saldo 596 596 0 0.00 31 46828 1135 0 0
srv_make_money_saldo 848 848 0 0.00 1 14822 44 0 0
srv_recalc_idx_stat 4 4 0 0.00 90852 318148 230679 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 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-norm:sp_add_invoice_to_stock 5442 5442 0 0.00 0 16068 197 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 2478 105 0 0
t$perf-norm:sp_cancel_client_order 1948 1948 0 0.00 0 4226 15 0 0
t$perf-norm:sp_cancel_customer_reserve 756 756 0 0.00 0 4716 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 744 744 0 0.00 0 2127 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 948 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 666 7 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 35180 1818 0 0
t$perf-norm:sp_cancel_write_off 1157 1157 0 0.00 0 1347 8 0 0
t$perf-norm:sp_client_order 22699 22699 0 0.00 0 14492 16 0 0
t$perf-norm:sp_customer_reserve 1092 1092 0 0.00 0 1118 6 0 0
t$perf-norm:sp_pay_from_customer 7601 7601 0 0.00 0 4338 10 0 0
t$perf-norm:sp_pay_to_supplier 6356 6356 0 0.00 0 7666 14 0 0
t$perf-norm:sp_reserve_write_off 10847 10847 0 0.00 0 9093 15 0 0
t$perf-norm:sp_supplier_invoice 6013 6013 0 0.00 0 4723 15 0 0
t$perf-norm:sp_supplier_order 5854 5854 0 0.00 0 6422 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 423 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 495 14 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 2817 28 0 0
t$perf-norm:srv_make_money_saldo 848 848 0 0.00 0 10865 34 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 11048 10868 180 1.62 0 7842 21 0 180
v_all_customers 41200 41200 0 0.00 0 16 1 0 0
v_all_suppliers 32772 32772 0 0.00 0 55 1 0 0
v_all_wares 45396 45396 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 123 5 0 0
v_cancel_client_order 3896 3896 0 0.00 0 7449 10 0 0
v_cancel_customer_prepayment 1488 1488 0 0.00 0 6663 15 0 0
v_cancel_customer_reserve 1512 1512 0 0.00 0 3399 8 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 4181 11 0 0
v_cancel_supplier_invoice 874 854 20 2.28 0 3584 17 0 20
v_cancel_supplier_order 848 848 0 0.00 0 4350 10 0 0
v_cancel_write_off 2314 2314 0 0.00 0 1115 13 0 0
v_max_id_clo_ord 5851 5851 0 0.00 0 289 1 0 0
v_max_id_clo_res 1092 1092 0 0.00 0 6405 18 0 0
v_max_id_ord_sup 6010 6010 0 0.00 0 5904 4 0 0
v_max_non_paid_invoice 1829 1829 0 0.00 0 1112 3 0 0
v_max_non_paid_realizn 4060 4060 0 0.00 0 5539 5 0 0
v_min_id_clo_ord 5851 5851 0 0.00 0 3558 7 0 0
v_min_id_clo_res 1092 1092 0 0.00 0 4390 19 0 0
v_min_id_ord_sup 6010 6010 0 0.00 0 5550 6 0 0
v_min_non_paid_invoice 6354 6354 0 0.00 0 18597 24 0 0
v_min_non_paid_realizn 7601 7601 0 0.00 0 7796 20 0 0
v_our_firm 11278 11278 0 0.00 0 17 1 0 0
v_reserve_write_off 21694 21694 0 0.00 0 7713 7 0 0
x_lock_depdocs_on_canc_invoice 11340 11340 0 0.00 0 23989 109 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 15721 336 0 0
x_make_qstorno_1000_1200 5854 5854 0 0.00 1 23369 1091 0 0
x_make_qstorno_1200_2000 6013 6013 0 0.00 2 72324 2957 0 0
x_make_qstorno_2100_3300 495062 495062 0 0.00 0 51415 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-02-2023 22:39:52 to 27-02-2023 22:39:56.

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
20:08:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:44 30007 8192000000 9904999920 10218594304 984682032 10.730 100 82 164 0 1012141008 5963424 21495552
20:11:21 25301 8192000000 9958890944 10284834816 1042060944 11.284 100 88 176 0 1072321616 8418480 21842192
20:12:52 9197 8192000000 9991808064 10328932352 1078315840 11.631 100 83 166 0 1109150016 9980112 20854064
20:14:03 6680 8192000000 10034941488 10379542528 1115265920 11.982 100 81 162 1 1145668768 11074464 19328384
20:15:18 8316 8192000000 10075456496 10433654784 1158389120 12.388 100 78 154 0 1187944192 11076512 18478560
20:16:30 4863 8192000000 10120030784 10491195392 1210762784 12.876 100 69 137 0 1237321648 11118128 15440736
20:17:39 3181 8192000000 10152807728 10527412224 1242313936 13.168 100 79 158 0 1272667264 12716256 17637072
20:18:46 1764 8192000000 10181573344 10560376832 1267696896 13.401 100 87 173 0 1301152864 15181728 18274240
20:19:50 1528 8192000000 10201513648 10584756224 1288946016 13.595 100 86 170 2 1322761472 14854784 18960672
20:20:53 12649 8192000000 10206091568 10608173056 1309182368 13.779 100 64 128 0 1330801600 6551824 15067408
20:22:08 1029 8192000000 10229166240 10623692800 1319458640 13.872 100 70 143 2 1346633728 11501968 15673120
20:23:11 2535 8192000000 10220964656 10632081408 1326669584 13.937 100 38 77 0 1347330560 11998512 8662464
20:24:14 2769 8192000000 10219325696 10633314304 1333092496 13.995 100 27 54 0 1344187136 5111920 5982720
20:25:22 14186 8192000000 10240715440 10648752128 1340220544 14.059 100 32 58 4 1353015152 5572000 7222608
20:26:38 10331 8192000000 10237281360 10650071040 1329962576 13.967 99 46 92 0 1346640944 5531824 11146544
20:27:49 3903 8192000000 10227475696 10642341888 1326425040 13.935 99 40 79 0 1348031136 12669104 8936992
20:28:55 7116 8192000000 10203013456 10617540608 1310749280 13.793 100 20 40 0 1325211376 10196816 4265280
20:30:04 37997 8192000000 10184420048 10601189376 1276089776 13.477 97 25 50 0 1287841072 6260384 5490912
20:31:43 7276 8192000000 10163698144 10572439552 1225491072 13.012 95 18 37 0 1234937376 5321872 4124432
20:32:53 6281 8192000000 10152727680 10550038528 1233705008 13.088 97 19 38 0 1243453104 5385504 4362592
20:33:59 11698 8192000000 10158839024 10560073728 1260673520 13.336 99 21 41 0 1270755264 5427040 4654704
20:35:14 15294 8192000000 10158470128 10572730368 1257290720 13.305 98 28 56 0 1270134800 6807216 6036864
20:36:33 5429 8192000000 10199798480 10608369664 1300657296 13.701 100 37 74 1 1319314384 10717504 7939584
20:37:41 11028 8192000000 10218456000 10628292608 1315034256 13.832 100 23 47 0 1325337680 5230576 5072848
20:38:56 25656 8192000000 10241421616 10651836416 1342323920 14.078 100 40 80 0 1357137696 6067792 8745984
20:40:24 38484 8192000000 10258318096 10676633600 1356923648 14.210 100 47 94 0 1375496304 8115712 10456944
20:42:06 8435 8192000000 10280822848 10700075008 1371216400 14.338 100 45 90 2 1392055712 10744208 10095104
20:43:18 11461 8192000000 10282123280 10702028800 1372122688 14.346 100 48 96 0 1394672640 12085936 10464016
20:44:34 23624 8192000000 10285993152 10704601088 1366416912 14.295 99 52 104 0 1393400800 15073504 11910384
20:46:04 26019 8192000000 10310708064 10734518272 1393990560 14.541 100 50 98 2 1421639216 16224192 11424464
20:47:58 11484 8192000000 10301887984 10728046592 1389894640 14.505 100 37 74 0 1417013728 18061424 9057664
20:49:16 7497 8192000000 10306314416 10737803264 1392952672 14.532 100 40 80 0 1424801872 21902112 9947088
20:50:29 3965 8192000000 10312174000 10740379648 1392810352 14.531 100 48 96 1 1429922832 25110416 12002064
20:51:41 5377 8192000000 10331974864 10751688704 1396132672 14.561 100 64 126 0 1439246976 28466272 14648032
20:52:53 10295 8192000000 10346556208 10766692352 1406478576 14.653 100 67 133 1 1457158000 34454704 16224720
20:54:08 10411 8192000000 10324333648 10743566336 1393289472 14.535 100 59 118 0 1438908400 30683088 14935840
20:55:22 4399 8192000000 10339396864 10760101888 1396957968 14.568 100 56 110 1 1443846464 33016928 13871568
20:56:34 5903 8192000000 10333118016 10754883584 1395068240 14.551 100 50 100 0 1443202144 35914000 12219904
20:57:45 908 8192000000 10349080208 10753449984 1389155040 14.498 99 81 159 0 1447123872 39026048 18942784
20:58:51 2010 8192000000 10319829072 10731831296 1367078016 14.301 99 65 129 0 1425437728 41870320 16489392
21:00:01 3151 8192000000 10344780672 10751774720 1356896752 14.209 98 74 147 0 1434696512 58313680 19486080
21:01:06 15584 8192000000 10335765376 10762407936 1360294976 14.240 99 52 104 1 1441318432 66688096 14335360
21:02:25 9901 8192000000 10320710624 10745167872 1364916656 14.281 100 33 66 0 1426807168 52190880 9699632
21:03:42 14446 8192000000 10323651488 10752102400 1364870976 14.281 99 38 77 1 1418996720 43897584 10228160
21:04:57 11207 8192000000 10327858368 10757804032 1377157728 14.391 100 25 48 1 1431128608 47697664 6273216
21:06:10 20968 8192000000 10317416208 10757922816 1367834864 14.308 100 25 49 0 1420458752 45842480 6781408
21:07:33 29658 8192000000 10318093120 10753855488 1366854864 14.299 100 31 62 2 1430535536 55346416 8334256
21:09:04 59671 8192000000 10261069296 10710032384 1341294368 14.069 98 13 26 0 1368054832 23704400 3056064
21:11:09 75963 8192000000 10304859440 10746130432 1366491920 14.296 99 10 20 1 1385407408 17169920 1745568
21:13:26 69250 8192000000 10290183632 10721972224 1350347040 14.151 98 22 44 1 1363669776 8031472 5291264
21:15:40 26740 8192000000 10294307104 10722570240 1362288720 14.258 99 58 114 0 1386339408 10410000 13640688
21:17:10 99000 8192000000 10293150304 10738475008 1357130816 14.212 98 12 23 2 1369516304 9472272 2913216
21:20:00 69128 8192000000 10303717968 10764890112 1393045200 14.533 100 12 24 0 1412595232 16548912 3001120
21:22:10 58912 8192000000 10311585328 10757881856 1369337680 14.321 98 48 94 3 1389305152 9117472 10850000
21:24:12 22011 8192000000 10308851360 10763497472 1391245552 14.517 100 33 66 0 1410148016 11952016 6950448
21:25:45 11552 8192000000 10324028896 10767015936 1383872992 14.451 99 50 100 0 1413685424 18162944 11649488
21:27:06 31652 8192000000 10312348752 10760794112 1385537872 14.466 99 31 62 0 1409942912 17085024 7320016
21:28:46 37260 8192000000 10306904992 10770526208 1383466464 14.448 99 20 40 0 1406463872 17936784 5060624
21:30:24 34965 8192000000 10288604128 10747240448 1376070832 14.381 99 9 20 1 1387619936 9330320 2218784
21:32:03 17268 8192000000 10269627072 10731663360 1366167008 14.293 99 7 13 0 1379398928 11693280 1538640
21:33:22 52066 8192000000 10274055456 10739568640 1362843920 14.263 99 17 34 0 1374017168 7129200 4044048
21:35:25 64621 8192000000 10268717984 10732900352 1358521232 14.224 99 19 39 0 1376657392 13784160 4352000
21:37:58 51703 8192000000 10263828176 10726854656 1368546608 14.314 100 9 18 0 1380073728 9544912 1982208
21:40:30 41813 8192000000 10284924656 10743083008 1364218592 14.275 99 19 38 0 1385421280 16543504 4659184
21:42:12 58693 8192000000 10287599088 10753757184 1367403424 14.304 99 14 28 0 1387249568 16619792 3226352
21:44:11 39128 8192000000 10263502816 10724048896 1334746080 14.010 98 18 35 0 1357702752 18547584 4409088
21:45:51 29278 8192000000 10268205136 10705309696 1332895904 13.993 99 37 73 0 1356311040 15523440 7891696
21:47:23 78692 8192000000 10258700096 10703986688 1312275168 13.807 97 8 16 0 1329245024 15112192 1857664
21:49:56 43829 8192000000 10263925728 10710511616 1317522928 13.854 98 22 44 0 1342236816 19637120 5076768
21:51:44 96954 8192000000 10267201888 10715496448 1337515616 14.035 99 17 34 1 1353925200 12605248 3804336
21:54:21 78293 8192000000 10245285904 10700242944 1316748208 13.847 97 19 38 0 1332145792 10556560 4841024
21:56:41 35917 8192000000 10250863264 10699472896 1332452016 13.989 99 25 50 0 1349852192 11461584 5938592
21:58:18 61999 8192000000 10241457776 10698739712 1341592720 14.072 100 12 25 0 1358204256 13884528 2727008
22:00:20 48223 8192000000 10266565072 10720878592 1360884672 14.245 100 19 38 1 1373524144 7893056 4746416
22:02:18 45751 8192000000 10263320368 10727026688 1367350656 14.303 100 12 24 0 1380699360 10152336 3196368
22:04:06 65572 8192000000 10277974560 10748928000 1378973536 14.407 100 11 22 0 1391679520 10204272 2501712
22:06:20 69692 8192000000 10282041616 10744397824 1368058736 14.310 99 12 24 0 1384394464 13691200 2644528
22:08:36 88332 8192000000 10286319280 10750304256 1347647520 14.126 97 17 33 2 1364790960 13236352 3907088
22:11:09 56190 8192000000 10306404176 10759184384 1391067456 14.515 100 24 48 0 1408226768 12070224 5089088
22:13:08 11842 8192000000 10316608032 10761797632 1396539664 14.564 100 39 78 0 1415080080 10031888 8508528
22:14:21 86790 8192000000 10303296944 10751229952 1348725024 14.136 96 28 56 0 1364878272 9987984 6165264
22:16:49 108837 8192000000 10300594944 10738249728 1273482976 13.453 91 22 44 0 1287914000 9657792 4773232
22:19:42 86667 8192000000 10271342624 10734104576 1324294384 13.916 96 5 10 0 1339236176 14001696 940096
22:22:42 31807 8192000000 10276107472 10726903808 1371772864 14.343 100 30 60 0 1385862128 7861376 6227888
22:24:28 46626 8192000000 10264683488 10732064768 1336826112 14.029 97 15 30 1 1350246896 9216224 4204560
22:26:22 57336 8192000000 10249867312 10710274048 1298128224 13.678 95 11 21 0 1306474496 6252848 2093424
22:28:20 105970 8192000000 10251389168 10712006656 1325275552 13.924 97 17 34 2 1341541792 11936528 4329712
22:31:19 76490 8192000000 10248712432 10709135360 1300158544 13.697 95 9 18 0 1312739552 10365152 2215856
22:33:36 8907 8192000000 10262712384 10708516864 1330947936 13.976 98 33 66 0 1349713888 11319376 7446576
22:34:48 12641 8192000000 10270145712 10714513408 1356893088 14.209 100 39 78 0 1375215088 9913856 8408144
22:36:04 38795 8192000000 10271783056 10715336704 1314464048 13.827 97 36 70 0 1337711488 15579552 7667888
22:37:44 46391 8192000000 10285680288 10725752832 1342721584 14.082 99 33 66 0 1366438496 15743488 7973424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-02-2023 22:39:58 to 27-02-2023 22:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 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 27-02-2023 22:40:15 to 27-02-2023 22:40:17.

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          251249
MON$OLDEST_ACTIVE               252711
MON$OLDEST_SNAPSHOT             252711
MON$NEXT_TRANSACTION            252711
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                       2039200
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 ---
:736: 
:737: mscs-test-161.budzdorov.ru    Mon Feb 27 22:38:55 2023
:738:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:739: 
--- end of diff output ---

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

27.02.23 22:40:18. 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-02-27 22:40:20 No severe PSQL-related problems occured
27.02.23 22:40:22. End of report.