Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.2022 22: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 16386564 kB
MemAvailable 31211612 kB
Buffers 0 kB
Cached 15313672 kB
SwapCached 2476 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-22 22:38:19
DTS_END                         2022-06-22 22:38:19.0310
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/38294
TRN_ID                          248112
ATT_ID                          732
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: 9280.90 20670 1113708
customer order: creation 177.79 349 21335
customer order: refuse 14.99 329 1799
order to supplier: creation 46.32 1213 5558
order to supplier: removal 3.40 96562 408
invoice (draft): creation 47.07 3342 5648
invoice (draft): removal 53.01 763 6361
invoice accept: apply 42.86 18410 5143
invoice accept: cancel 47.62 855 5714
customer reserve: creation 3909.75 186 469170
customer reserve: removal 4628.53 55 555424
realization accept: apply 86.23 176 10347
realization accept: cancel 88.35 118 10602
payment from customer: creation 60.94 124 7313
payment from customer: removal 5.90 73 708
payment to supplier: creation 51.21 170 6145
payment to supplier: removal 3.57 59 428
service: total inventory turnovers 5.58 925 669
service: total monetary turnovers 6.83 22 820
service: aggregate perf. data 0.93 19507 112
service: refresh index statistics 0.03 270166 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2022 22:39:08 to 22-06-2022 22:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6267
TEST_TIME 2 3953
TEST_TIME 3 3985
TEST_TIME 4 4608
TEST_TIME 5 4182
TEST_TIME 6 4198
TEST_TIME 7 4291
TEST_TIME 8 4608
TEST_TIME 9 4836
TEST_TIME 10 4925
TEST_TIME 11 5095
TEST_TIME 12 5258
TEST_TIME 13 5685
TEST_TIME 14 5919
TEST_TIME 15 6468
TEST_TIME 16 7017
TEST_TIME 17 7222
TEST_TIME 18 8022
TEST_TIME 19 8213
TEST_TIME 20 8320
TEST_TIME 21 8845
TEST_TIME 22 9500
TEST_TIME 23 10495
TEST_TIME 24 10378
TEST_TIME 25 10158
TEST_TIME 26 10200
TEST_TIME 27 10020
TEST_TIME 28 9789
TEST_TIME 29 9670
TEST_TIME 30 9785
TEST_TIME 31 9678
TEST_TIME 32 9635
TEST_TIME 33 9623
TEST_TIME 34 9678
TEST_TIME 35 9687
TEST_TIME 36 9556
TEST_TIME 37 9466
TEST_TIME 38 9366
TEST_TIME 39 9421
TEST_TIME 40 9679
TEST_TIME 41 9652
TEST_TIME 42 9588
TEST_TIME 43 9691
TEST_TIME 44 9636
TEST_TIME 45 9967
TEST_TIME 46 9918
TEST_TIME 47 9899
TEST_TIME 48 10058
TEST_TIME 49 10072
TEST_TIME 50 10046
TEST_TIME 51 9911
TEST_TIME 52 9747
TEST_TIME 53 9655
TEST_TIME 54 9705
TEST_TIME 55 9731
TEST_TIME 56 9688
TEST_TIME 57 9657
TEST_TIME 58 9627
TEST_TIME 59 9534
TEST_TIME 60 9482
TEST_TIME 61 9401
TEST_TIME 62 9467
TEST_TIME 63 9415
TEST_TIME 64 9476
TEST_TIME 65 9509
TEST_TIME 66 9530
TEST_TIME 67 9543
TEST_TIME 68 9503
TEST_TIME 69 9543
TEST_TIME 70 9535
TEST_TIME 71 9690
TEST_TIME 72 9754
TEST_TIME 73 9703
TEST_TIME 74 9827
TEST_TIME 75 9776
TEST_TIME 76 9672
TEST_TIME 77 9587
TEST_TIME 78 9564
TEST_TIME 79 9549
TEST_TIME 80 9483
TEST_TIME 81 9432
TEST_TIME 82 9400
TEST_TIME 83 9389
TEST_TIME 84 9383
TEST_TIME 85 9338
TEST_TIME 86 9427
TEST_TIME 87 9425
TEST_TIME 88 9384
TEST_TIME 89 9451
TEST_TIME 90 9459
TEST_TIME 91 9470
TEST_TIME 92 9465
TEST_TIME 93 9522
TEST_TIME 94 9539
TEST_TIME 95 9495
TEST_TIME 96 9512
TEST_TIME 97 9555
TEST_TIME 98 9487
TEST_TIME 99 9403
TEST_TIME 100 9390
TEST_TIME 101 9355
TEST_TIME 102 9328
TEST_TIME 103 9270
TEST_TIME 104 9319
TEST_TIME 105 9319
TEST_TIME 106 9281
TEST_TIME 107 9202
TEST_TIME 108 9160
TEST_TIME 109 9236
TEST_TIME 110 9252
TEST_TIME 111 9225
TEST_TIME 112 9236
TEST_TIME 113 9209
TEST_TIME 114 9260
TEST_TIME 115 9292
TEST_TIME 116 9305
TEST_TIME 117 9329
TEST_TIME 118 9305
TEST_TIME 119 9336
TEST_TIME 120 9282

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-06-2022 22:39:12 to 22-06-2022 22:39:15.

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 1547029 1547029 0 0.00 0 14819 7 0 0
doc_list_biud 1547029 1547029 0 0.00 0 22965 26 0 0
sp_add_invoice_to_stock 5213 5143 70 1.34 37 174111 18410 0 70
sp_cancel_adding_invoice 5714 5714 0 0.00 5 66447 855 0 0
sp_cancel_client_order 1799 1799 0 0.00 4 10580 330 0 0
sp_cancel_customer_reserve 555424 555424 0 0.00 1 22967 56 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 2 5195 73 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 2020 59 0 0
sp_cancel_supplier_invoice 6368 6361 7 0.10 2 16855 764 0 7
sp_cancel_supplier_order 408 408 0 0.00 8 997102 96562 0 0
sp_cancel_write_off 10602 10602 0 0.00 1 13056 118 0 0
sp_client_order 21335 21335 0 0.00 3 47958 350 0 0
sp_customer_reserve 469170 469170 0 0.00 0 95809 186 0 0
sp_fill_shopping_cart 32538 32538 0 0.00 1 13280 38 0 0
sp_fill_shopping_cart_clo_res 469167 469167 0 0.00 0 15755 15 0 0
sp_get_clo_for_invoice 5140 5140 0 0.00 0 17582 288 0 0
sp_kill_cost_storno 17452 17452 0 0.00 0 8559 51 0 0
sp_kill_qstorno_ret_qs2qd 1128219 1128219 0 0.00 0 22962 30 0 0
sp_kill_qty_storno 595795 595795 0 0.00 0 22964 66 0 0
sp_lock_selected_doc 21444 21444 0 0.00 0 8698 8 0 0
sp_make_cost_storno 28945 28945 0 0.00 0 14420 63 0 0
sp_multiply_rows_for_pdistr 28945 28945 0 0.00 0 7041 27 0 0
sp_multiply_rows_for_qdistr 21335 21335 0 0.00 0 47947 154 0 0
sp_pay_from_customer 7313 7313 0 0.00 4 12346 124 0 0
sp_pay_to_supplier 6145 6145 0 0.00 4 17320 170 0 0
sp_payment_common 13458 13458 0 0.00 1 9783 35 0 0
sp_qd_handle_on_cancel_clo 1799 1799 0 0.00 0 10566 301 0 0
sp_qd_handle_on_invoice_upd_sts 10854 10854 0 0.00 0 13469 335 0 0
sp_qd_handle_on_reserve_upd_sts 10347 10347 0 0.00 0 14963 56 0 0
sp_reserve_write_off 10347 10347 0 0.00 6 14987 176 0 0
sp_supplier_invoice 5648 5648 0 0.00 7 120936 3342 0 0
sp_supplier_order 5558 5558 0 0.00 6 32247 1213 0 0
srv_aggregate_perf_data 112 112 0 0.00 2454 250721 19508 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1027 153006 45322 0 0
srv_find_qd_qs_mism 1064118 1064118 0 0.00 0 14816 6 0 0
srv_make_invnt_saldo 669 669 0 0.00 34 32847 926 0 0
srv_make_money_saldo 820 820 0 0.00 1 644 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 229931 294642 270166 0 0
t$perf-abend:sp_add_invoice_to_stock 70 70 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5143 5143 0 0.00 0 14184 148 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 1 7273 108 0 0
t$perf-norm:sp_cancel_client_order 1799 1799 0 0.00 0 9994 24 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 929 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 1361 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1092 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 614 9 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 23399 1540 0 0
t$perf-norm:sp_cancel_write_off 1054 1054 0 0.00 0 6281 14 0 0
t$perf-norm:sp_client_order 21335 21335 0 0.00 0 8858 18 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 7199 15 0 0
t$perf-norm:sp_pay_from_customer 7313 7313 0 0.00 0 5732 9 0 0
t$perf-norm:sp_pay_to_supplier 6145 6145 0 0.00 0 6755 11 0 0
t$perf-norm:sp_reserve_write_off 10347 10347 0 0.00 0 12160 12 0 0
t$perf-norm:sp_supplier_invoice 5648 5648 0 0.00 0 10380 17 0 0
t$perf-norm:sp_supplier_order 5558 5558 0 0.00 0 6145 15 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 172 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 45 2 0 0
t$perf-norm:srv_make_invnt_saldo 669 669 0 0.00 0 598 15 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 597 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10416 10276 140 1.34 0 6197 15 0 140
v_all_customers 38568 38568 0 0.00 0 215 1 0 0
v_all_suppliers 31196 31196 0 0.00 0 103 1 0 0
v_all_wares 42670 42670 0 0.00 0 196 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 1982 9 0 0
v_cancel_client_order 3598 3598 0 0.00 0 1226 3 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 4316 8 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 2195 4 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 683 3 0 0
v_cancel_supplier_invoice 888 874 14 1.57 0 8546 24 0 14
v_cancel_supplier_order 816 816 0 0.00 0 364 3 0 0
v_cancel_write_off 2108 2108 0 0.00 0 4531 19 0 0
v_max_id_clo_ord 5557 5557 0 0.00 0 824 2 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 1504 8 0 0
v_max_id_ord_sup 5646 5646 0 0.00 0 12454 6 0 0
v_max_non_paid_invoice 1750 1750 0 0.00 0 2992 4 0 0
v_max_non_paid_realizn 4054 4054 0 0.00 0 3514 6 0 0
v_min_id_clo_ord 5557 5557 0 0.00 0 6400 7 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 4455 10 0 0
v_min_id_ord_sup 5646 5646 0 0.00 0 6628 10 0 0
v_min_non_paid_invoice 6145 6145 0 0.00 0 7308 25 0 0
v_min_non_paid_realizn 7313 7313 0 0.00 0 5987 22 0 0
v_our_firm 10620 10620 0 0.00 0 78 1 0 0
v_reserve_write_off 20694 20694 0 0.00 0 3001 4 0 0
x_lock_depdocs_on_canc_invoice 11638 11638 0 0.00 0 30211 119 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 6912 303 0 0
x_make_qstorno_1000_1200 5558 5558 0 0.00 1 32206 1135 0 0
x_make_qstorno_1200_2000 5648 5648 0 0.00 3 120868 3202 0 0
x_make_qstorno_2100_3300 469170 469170 0 0.00 0 95804 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2022 22:39:16 to 22-06-2022 22:39:20.

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:22 2 8192000000 8797894160 8808792064 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:24 21169 8192000000 9914896144 10228191232 996248320 10.842 100 84 168 0 1024145792 5442128 22455344
20:10:47 22141 8192000000 9957642064 10280198144 1039792128 11.263 100 92 184 0 1071351920 8041184 23518608
20:12:12 14517 8192000000 9980688576 10311204864 1063086752 11.486 100 83 166 0 1093289728 9455296 20747680
20:13:33 15491 8192000000 10036302864 10384437248 1117275728 12.001 100 80 158 1 1146529728 10485312 18768688
20:14:53 7659 8192000000 10088255840 10450776064 1180345296 12.593 100 80 160 1 1211747200 12557072 18844832
20:16:03 5346 8192000000 10137413040 10512338944 1228769920 13.043 100 82 164 1 1259687728 12467792 18450016
20:17:10 3430 8192000000 10175932752 10550710272 1256695600 13.300 100 88 176 0 1290353360 14885088 18772672
20:18:15 1537 8192000000 10198256848 10579820544 1288184704 13.588 100 90 176 3 1319560432 11663376 19712352
20:19:17 2526 8192000000 10216078080 10609438720 1308931248 13.776 100 67 134 0 1335085200 11400336 14753616
20:20:29 3613 8192000000 10207836048 10610462720 1309871280 13.785 100 39 80 4 1324640288 5371504 9397504
20:21:35 7447 8192000000 10208480080 10620923904 1320101696 13.878 100 24 48 0 1332999104 7324464 5572944
20:22:46 12537 8192000000 10215840064 10634326016 1327914880 13.948 100 22 44 0 1338400688 5929504 4556304
20:24:02 75175 8192000000 10220755056 10645417984 1321694176 13.892 99 24 48 2 1333392000 5393648 6304176
20:26:17 4926 8192000000 10229904160 10651365376 1339425584 14.052 100 22 44 0 1350016256 6176560 4414112
20:27:23 7830 8192000000 10206622336 10618912768 1282673504 13.537 97 20 40 0 1297775936 10203888 4898544
20:28:31 8129 8192000000 10193685504 10606182400 1272949600 13.449 97 18 35 2 1282216608 4865824 4401184
20:29:40 16513 8192000000 10184084944 10588631040 1260526880 13.335 97 55 110 1 1278522208 5753136 12242192
20:31:03 6395 8192000000 10116382720 10508562432 1199783760 12.774 97 29 58 1 1212651712 6065184 6802768
20:32:11 8543 8192000000 10157002640 10557001728 1240731696 13.153 98 29 58 0 1253667328 5676624 7259008
20:33:24 29476 8192000000 10153368208 10563457024 1267030912 13.394 100 22 43 2 1277152304 5337536 4783856
20:35:32 8825 8192000000 10180191824 10588041216 1286855120 13.576 100 28 57 1 1299666144 6381184 6429840
20:36:41 8820 8192000000 10187722976 10597154816 1292535360 13.627 100 35 70 1 1306415008 5779280 8100368
20:37:52 12829 8192000000 10202995456 10616561664 1303996256 13.732 100 20 40 2 1313453920 5530832 3926832
20:39:09 10510 8192000000 10248227008 10652184576 1335923888 14.021 100 45 90 2 1353698624 7558576 10216160
20:40:29 149800 8192000000 10280829088 10697904128 1358284128 14.222 100 41 82 2 1388174128 20011232 9878768
20:44:02 18816 8192000000 10297157328 10713509888 1357625296 14.216 98 39 78 1 1390669408 24031632 9012480
20:45:33 5457 8192000000 10327745648 10747961344 1398801872 14.584 100 50 98 2 1439599296 28857920 11939504
20:46:46 1027 8192000000 10353048704 10756751360 1404118720 14.632 100 76 152 0 1456221968 33222960 18880288
20:47:52 3665 8192000000 10341359552 10751631360 1380920864 14.425 98 72 144 1 1432843584 33623648 18299072
20:48:58 6592 8192000000 10339435504 10755002368 1393902784 14.541 99 60 118 0 1444298688 35805648 14590256
20:50:11 2447 8192000000 10365936720 10775805952 1404984592 14.639 100 78 154 0 1468746784 45081248 18680944
20:51:19 11861 8192000000 10351628896 10767433728 1388436144 14.492 99 61 120 2 1451236528 47770688 15029696
20:52:40 3347 8192000000 10370270080 10772516864 1396939200 14.568 100 86 171 1 1471081872 52672272 21470400
20:53:48 2129 8192000000 10325751936 10723618816 1356215808 14.203 98 76 152 1 1427569696 51280416 20073472
20:54:54 13104 8192000000 10320256320 10738470912 1356859344 14.209 99 46 92 0 1425245360 55440928 12945088
20:56:23 1652 8192000000 10355932000 10767872000 1390617008 14.511 100 76 152 1 1463805856 53801056 19387792
20:57:29 16217 8192000000 10315520560 10746179584 1363363936 14.268 99 34 69 0 1414084128 41670640 9049552
20:58:54 16713 8192000000 10343801056 10769416192 1381903632 14.434 100 49 99 0 1445218704 50103216 13211856
21:00:15 19180 8192000000 10271890144 10715435008 1334370464 14.007 98 16 32 1 1377357376 37897152 5089760
21:01:36 38222 8192000000 10252706960 10686644224 1325067456 13.923 98 10 20 3 1341913008 14199600 2645952
21:03:15 32624 8192000000 10282447856 10706792448 1347991792 14.129 99 55 110 2 1369491584 7822352 13677440
21:04:52 35389 8192000000 10244186192 10681479168 1314198160 13.824 97 23 45 1 1325715600 6534032 4983408
21:06:30 50106 8192000000 10262308960 10704224256 1343481008 14.089 99 33 68 0 1361667104 10155056 8031040
21:08:22 31249 8192000000 10264368528 10709868544 1358666144 14.225 100 24 48 1 1383249360 19393104 5190112
21:09:57 22740 8192000000 10291949360 10729095168 1372299376 14.348 99 38 77 1 1390867632 9651392 8916864
21:11:24 44720 8192000000 10300902688 10750185472 1392091584 14.525 100 21 42 0 1409086816 11800320 5194912
21:13:51 36562 8192000000 10296472304 10741686272 1373128176 14.355 99 25 50 0 1401773616 22951200 5694240
21:15:28 53056 8192000000 10302899952 10758926336 1393959984 14.541 100 14 28 0 1418505536 21091088 3454464
21:17:33 33437 8192000000 10298309824 10763124736 1383466448 14.448 100 16 32 2 1410648560 22859488 4322624
21:19:10 76488 8192000000 10305100608 10756616192 1375392096 14.375 98 26 52 2 1395125936 13398992 6334848
21:21:27 29570 8192000000 10323889920 10788904960 1404344384 14.634 100 18 36 1 1428263520 19993296 3925840
21:22:59 79624 8192000000 10309304208 10764496896 1387792432 14.486 99 11 22 0 1406826384 16266752 2767200
21:25:22 74136 8192000000 10307346624 10762625024 1393167264 14.534 99 18 36 0 1414957328 17323920 4466144
21:27:37 98396 8192000000 10295476752 10741592064 1377298032 14.392 98 12 24 1 1388862128 9310960 2253136
21:30:19 99872 8192000000 10289504848 10741592064 1356404400 14.205 98 38 76 0 1379813008 14424880 8983728
21:33:14 42678 8192000000 10262928112 10728935424 1354382160 14.187 98 15 30 0 1367742112 9817456 3542496
21:34:58 39210 8192000000 10259741120 10710851584 1332403568 13.989 97 16 32 0 1344360880 8072176 3885136
21:36:38 54673 8192000000 10227105328 10675867648 1276373168 13.480 93 10 20 0 1285399872 7013312 2013392
21:38:36 33686 8192000000 10228358784 10682609664 1283894032 13.549 95 13 24 0 1296684928 9858656 2932240
21:40:11 65505 8192000000 10258229056 10704326656 1321107344 13.887 97 12 24 0 1336662512 13199184 2355984
21:42:23 52501 8192000000 10266040384 10714652672 1330769216 13.974 97 17 32 1 1344969040 10551696 3648128
21:44:16 43503 8192000000 10261499360 10711224320 1320725232 13.883 97 28 56 0 1337669952 10886480 6058240
21:47:05 27715 8192000000 10314937184 10745929728 1370997296 14.336 100 53 106 2 1401609024 18673232 11938496
21:48:38 86292 8192000000 10284306512 10746568704 1336211984 14.023 97 20 40 0 1367173360 26468720 4492656
21:51:12 73818 8192000000 10283084976 10721271808 1312244512 13.806 96 20 39 1 1331457024 14962688 4249824
21:53:27 129999 8192000000 10254405840 10703273984 1339496512 14.053 98 19 38 0 1353023328 9450928 4075888
21:57:13 55442 8192000000 10255271312 10717118464 1353957088 14.183 99 7 14 1 1364947616 9429616 1560912
22:00:38 36726 8192000000 10258584976 10719420416 1366509088 14.296 100 5 10 1 1376828096 9248688 1070320
22:02:19 37266 8192000000 10276739680 10732982272 1372085696 14.346 100 26 52 1 1387102048 8745312 6271040
22:03:57 37461 8192000000 10284848064 10746433536 1375806144 14.379 99 31 62 2 1398249712 14819600 7623968
22:05:35 153006 8192000000 10290812928 10763395072 1360113008 14.238 98 7 14 0 1378944928 17311088 1520832
22:09:11 49307 8192000000 10301252368 10756493312 1376513472 14.385 99 31 62 0 1393665648 10566688 6585488
22:11:01 56178 8192000000 10295533312 10760810496 1379102288 14.409 99 9 18 0 1395425232 13895216 2427728
22:12:58 41170 8192000000 10319035824 10759266304 1383903664 14.451 99 41 81 0 1401433328 8416208 9113456
22:14:49 81600 8192000000 10299019376 10765176832 1375668112 14.378 98 17 34 2 1390970272 11893696 3408464
22:17:11 89875 8192000000 10294993408 10755821568 1395149776 14.552 100 20 40 0 1409334240 9319328 4865136
22:19:45 59713 8192000000 10304856544 10769727488 1391555024 14.520 99 9 18 1 1404468304 10779248 2134032
22:21:47 56886 8192000000 10295434272 10773594112 1377123312 14.391 98 12 24 0 1397225616 17377520 2724784
22:23:52 60258 8192000000 10265823504 10721611776 1325309184 13.925 95 15 30 0 1336386624 7527936 3549504
22:25:53 24247 8192000000 10273845936 10725019648 1352500848 14.170 98 29 57 1 1373272944 13329280 7442816
22:27:55 68946 8192000000 10256086384 10714415104 1289605264 13.601 94 10 21 1 1304756784 12663792 2487728
22:30:22 23605 8192000000 10271076656 10725122048 1342561936 14.081 98 46 92 2 1365092416 11429024 11101456
22:31:51 40987 8192000000 10278281664 10732802048 1338802032 14.047 98 23 46 0 1355752944 11954720 4996192
22:33:32 49027 8192000000 10286722464 10728632320 1337699392 14.037 98 32 64 0 1361675520 16586016 7390112
22:35:26 29525 8192000000 10272531904 10720464896 1350230320 14.150 99 26 52 0 1366739568 10698928 5810320
22:37:00 35725 8192000000 10285308304 10721443840 1362836272 14.263 100 45 90 1 1385668592 13078960 9753360

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-06-2022 22:39:22 to 22-06-2022 22:39:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 70 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-06-2022 22:39:38 to 22-06-2022 22:39:40.

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          245897
MON$OLDEST_ACTIVE               248566
MON$OLDEST_SNAPSHOT             248566
MON$NEXT_TRANSACTION            248566
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                       2011840
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:300: 
:301: mscs-test-161.budzdorov.ru    Wed Jun 22 22:38:23 2022
:302:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:303: 
:304: 
:305: mscs-test-161.budzdorov.ru    Wed Jun 22 22:38:35 2022
:306:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:307: 
--- end of diff output ---

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

22.06.22 22:39:41. 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-06-22 22:39:43 No severe PSQL-related problems occured
22.06.22 22:39:44. End of report.