Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.05.2022 01:40
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 16530176 kB
MemAvailable 31447636 kB
Buffers 0 kB
Cached 15215164 kB
SwapCached 2588 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-05-22 01:38:21
DTS_END                         2022-05-22 01:38:21.9470
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/56750
TRN_ID                          246922
ATT_ID                          735
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: 9307.23 18322 1116868
customer order: creation 182.03 364 21843
customer order: refuse 15.43 396 1851
order to supplier: creation 48.32 1347 5798
order to supplier: removal 3.45 94648 414
invoice (draft): creation 48.34 3685 5801
invoice (draft): removal 50.84 822 6101
invoice accept: apply 44.29 17373 5315
invoice accept: cancel 45.74 737 5489
customer reserve: creation 4038.68 173 484642
customer reserve: removal 4525.51 57 543061
realization accept: apply 88.88 176 10665
realization accept: cancel 79.63 111 9556
payment from customer: creation 62.33 116 7480
payment from customer: removal 6.13 58 736
payment to supplier: creation 51.80 120 6216
payment to supplier: removal 3.43 68 412
service: total inventory turnovers 4.78 1031 573
service: total monetary turnovers 6.68 54 802
service: aggregate perf. data 0.91 16676 109
service: refresh index statistics 0.03 228426 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-05-2022 01:40:42 to 22-05-2022 01:40:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6118
TEST_TIME 2 4101
TEST_TIME 3 3849
TEST_TIME 4 3852
TEST_TIME 5 4033
TEST_TIME 6 4589
TEST_TIME 7 4660
TEST_TIME 8 4702
TEST_TIME 9 4954
TEST_TIME 10 5215
TEST_TIME 11 5987
TEST_TIME 12 6303
TEST_TIME 13 6523
TEST_TIME 14 6823
TEST_TIME 15 7011
TEST_TIME 16 7411
TEST_TIME 17 7530
TEST_TIME 18 7937
TEST_TIME 19 8236
TEST_TIME 20 8313
TEST_TIME 21 8879
TEST_TIME 22 9114
TEST_TIME 23 9179
TEST_TIME 24 9322
TEST_TIME 25 9504
TEST_TIME 26 10328
TEST_TIME 27 10415
TEST_TIME 28 10412
TEST_TIME 29 10979
TEST_TIME 30 10770
TEST_TIME 31 10935
TEST_TIME 32 10936
TEST_TIME 33 10803
TEST_TIME 34 10638
TEST_TIME 35 10423
TEST_TIME 36 10447
TEST_TIME 37 10462
TEST_TIME 38 10393
TEST_TIME 39 10147
TEST_TIME 40 10137
TEST_TIME 41 10150
TEST_TIME 42 10095
TEST_TIME 43 10087
TEST_TIME 44 10118
TEST_TIME 45 10042
TEST_TIME 46 10020
TEST_TIME 47 10023
TEST_TIME 48 9928
TEST_TIME 49 9749
TEST_TIME 50 9785
TEST_TIME 51 9770
TEST_TIME 52 9787
TEST_TIME 53 9677
TEST_TIME 54 9653
TEST_TIME 55 9644
TEST_TIME 56 9613
TEST_TIME 57 9568
TEST_TIME 58 9571
TEST_TIME 59 9573
TEST_TIME 60 9629
TEST_TIME 61 9738
TEST_TIME 62 9741
TEST_TIME 63 9805
TEST_TIME 64 9812
TEST_TIME 65 9804
TEST_TIME 66 9800
TEST_TIME 67 9860
TEST_TIME 68 9953
TEST_TIME 69 9937
TEST_TIME 70 9906
TEST_TIME 71 9846
TEST_TIME 72 9793
TEST_TIME 73 9725
TEST_TIME 74 9749
TEST_TIME 75 9706
TEST_TIME 76 9681
TEST_TIME 77 9628
TEST_TIME 78 9574
TEST_TIME 79 9552
TEST_TIME 80 9501
TEST_TIME 81 9504
TEST_TIME 82 9520
TEST_TIME 83 9516
TEST_TIME 84 9637
TEST_TIME 85 9630
TEST_TIME 86 9570
TEST_TIME 87 9675
TEST_TIME 88 9634
TEST_TIME 89 9658
TEST_TIME 90 9709
TEST_TIME 91 9710
TEST_TIME 92 9753
TEST_TIME 93 9675
TEST_TIME 94 9616
TEST_TIME 95 9581
TEST_TIME 96 9584
TEST_TIME 97 9585
TEST_TIME 98 9520
TEST_TIME 99 9528
TEST_TIME 100 9460
TEST_TIME 101 9414
TEST_TIME 102 9412
TEST_TIME 103 9445
TEST_TIME 104 9417
TEST_TIME 105 9401
TEST_TIME 106 9413
TEST_TIME 107 9488
TEST_TIME 108 9482
TEST_TIME 109 9495
TEST_TIME 110 9506
TEST_TIME 111 9468
TEST_TIME 112 9579
TEST_TIME 113 9521
TEST_TIME 114 9552
TEST_TIME 115 9547
TEST_TIME 116 9475
TEST_TIME 117 9448
TEST_TIME 118 9423
TEST_TIME 119 9367
TEST_TIME 120 9310

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-05-2022 01:40:45 to 22-05-2022 01:40:49.

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 1566015 1566015 0 0.00 0 21147 6 0 0
doc_list_biud 1566006 1566006 0 0.00 0 26682 26 0 0
sp_add_invoice_to_stock 5410 5315 95 1.75 33 183649 17374 0 95
sp_cancel_adding_invoice 5489 5489 0 0.00 3 76270 738 0 0
sp_cancel_client_order 1851 1851 0 0.00 4 9908 397 0 0
sp_cancel_customer_reserve 543061 543061 0 0.00 1 22602 57 0 0
sp_cancel_pay_from_customer 736 736 0 0.00 2 1403 59 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 2027 69 0 0
sp_cancel_supplier_invoice 6107 6101 6 0.09 2 21346 823 0 6
sp_cancel_supplier_order 414 414 0 0.00 6 898225 94648 0 0
sp_cancel_write_off 9556 9556 0 0.00 1 10939 111 0 0
sp_client_order 21843 21843 0 0.00 3 17941 365 0 0
sp_customer_reserve 484642 484642 0 0.00 0 53167 174 0 0
sp_fill_shopping_cart 33435 33435 0 0.00 1 29958 41 0 0
sp_fill_shopping_cart_clo_res 484629 484629 0 0.00 0 12255 13 0 0
sp_get_clo_for_invoice 5302 5302 0 0.00 0 30148 286 0 0
sp_kill_cost_storno 16193 16193 0 0.00 0 12820 52 0 0
sp_kill_qstorno_ret_qs2qd 1102193 1102193 0 0.00 0 22473 31 0 0
sp_kill_qty_storno 582435 582435 0 0.00 0 26682 68 0 0
sp_lock_selected_doc 21948 21948 0 0.00 0 8550 6 0 0
sp_make_cost_storno 29663 29663 0 0.00 0 21143 54 0 0
sp_multiply_rows_for_pdistr 29661 29661 0 0.00 0 18146 29 0 0
sp_multiply_rows_for_qdistr 21843 21843 0 0.00 0 15135 169 0 0
sp_pay_from_customer 7480 7480 0 0.00 4 18167 117 0 0
sp_pay_to_supplier 6216 6216 0 0.00 4 14464 121 0 0
sp_payment_common 13696 13696 0 0.00 2 14428 28 0 0
sp_qd_handle_on_cancel_clo 1851 1851 0 0.00 0 9719 369 0 0
sp_qd_handle_on_invoice_upd_sts 10789 10789 0 0.00 0 26682 311 0 0
sp_qd_handle_on_reserve_upd_sts 10663 10663 0 0.00 0 17470 63 0 0
sp_reserve_write_off 10665 10665 0 0.00 6 17542 176 0 0
sp_supplier_invoice 5801 5801 0 0.00 5 80516 3685 0 0
sp_supplier_order 5798 5798 0 0.00 5 61350 1347 0 0
srv_aggregate_perf_data 109 109 0 0.00 2903 135215 16676 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2271 130483 45460 0 0
srv_find_qd_qs_mism 1066808 1066808 0 0.00 0 17865 6 0 0
srv_make_invnt_saldo 573 573 0 0.00 60 33894 1031 0 0
srv_make_money_saldo 802 802 0 0.00 1 20300 55 0 0
srv_recalc_idx_stat 4 4 0 0.00 190764 280900 228426 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5315 5315 0 0.00 0 18613 131 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 8632 139 0 0
t$perf-norm:sp_cancel_client_order 1851 1851 0 0.00 0 1359 7 0 0
t$perf-norm:sp_cancel_customer_reserve 739 739 0 0.00 0 1556 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 736 736 0 0.00 0 1924 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1220 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 3665 21 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 34585 1632 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 856 6 0 0
t$perf-norm:sp_client_order 21843 21843 0 0.00 0 9588 16 0 0
t$perf-norm:sp_customer_reserve 988 988 0 0.00 0 2127 8 0 0
t$perf-norm:sp_pay_from_customer 7480 7480 0 0.00 0 4418 9 0 0
t$perf-norm:sp_pay_to_supplier 6216 6216 0 0.00 0 4768 9 0 0
t$perf-norm:sp_reserve_write_off 10665 10665 0 0.00 0 9741 10 0 0
t$perf-norm:sp_supplier_invoice 5801 5801 0 0.00 0 7156 18 0 0
t$perf-norm:sp_supplier_order 5798 5798 0 0.00 0 6024 11 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 487 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 316 6 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 2247 16 0 0
t$perf-norm:srv_make_money_saldo 802 802 0 0.00 0 2452 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10790 10600 190 1.76 0 6414 16 0 190
v_all_customers 39560 39560 0 0.00 0 17 1 0 0
v_all_suppliers 32060 32060 0 0.00 0 84 1 0 0
v_all_wares 43686 43686 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 5231 14 0 0
v_cancel_client_order 3702 3702 0 0.00 0 9480 7 0 0
v_cancel_customer_prepayment 1472 1472 0 0.00 0 1146 5 0 0
v_cancel_customer_reserve 1478 1478 0 0.00 0 1605 5 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 883 6 0 0
v_cancel_supplier_invoice 838 826 12 1.43 0 3340 18 0 12
v_cancel_supplier_order 828 828 0 0.00 0 3247 10 0 0
v_cancel_write_off 2002 2002 0 0.00 0 970 12 0 0
v_max_id_clo_ord 5798 5798 0 0.00 0 631 1 0 0
v_max_id_clo_res 988 988 0 0.00 0 8126 22 0 0
v_max_id_ord_sup 5794 5794 0 0.00 0 2377 4 0 0
v_max_non_paid_invoice 1778 1778 0 0.00 0 1313 5 0 0
v_max_non_paid_realizn 4170 4170 0 0.00 0 2961 4 0 0
v_min_id_clo_ord 5798 5798 0 0.00 0 4531 5 0 0
v_min_id_clo_res 988 988 0 0.00 0 3006 8 0 0
v_min_id_ord_sup 5794 5794 0 0.00 0 12941 13 0 0
v_min_non_paid_invoice 6216 6216 0 0.00 0 14419 19 0 0
v_min_non_paid_realizn 7480 7480 0 0.00 0 8861 19 0 0
v_our_firm 10746 10746 0 0.00 0 83 1 0 0
v_reserve_write_off 21326 21326 0 0.00 0 5233 5 0 0
x_lock_depdocs_on_canc_invoice 11177 11177 0 0.00 0 18354 117 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 25139 352 0 0
x_make_qstorno_1000_1200 5798 5798 0 0.00 1 61340 1268 0 0
x_make_qstorno_1200_2000 5801 5801 0 0.00 2 79775 3527 0 0
x_make_qstorno_2100_3300 484642 484642 0 0.00 0 53165 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-05-2022 01:40:50 to 22-05-2022 01:40:54.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:25 7 8192000000 8798902176 8810233856 5315664 0.064 2 1 2 0 5415264 56816 42784
23:09:27 32459 8192000000 9909918960 10224582656 985228720 10.735 100 87 174 0 1014990864 6294688 23467456
23:11:03 32603 8192000000 9953064048 10277330944 1032441136 11.192 100 87 173 0 1062815824 8030400 22344288
23:13:00 12948 8192000000 9994453888 10336743424 1085043488 11.696 100 74 148 0 1113144032 9867728 18232816
23:14:17 13625 8192000000 10056880592 10408157184 1137181456 12.189 100 80 161 0 1167701664 11990928 18529280
23:15:35 8722 8192000000 10110003536 10472189952 1194227504 12.723 100 82 164 0 1225270928 12845744 18197680
23:16:46 3282 8192000000 10146494512 10523496448 1242068384 13.165 100 80 157 1 1270962272 11598640 17295248
23:17:51 3555 8192000000 10180331248 10557124608 1267351440 13.397 100 85 170 0 1299877344 13240080 19285824
23:18:56 2154 8192000000 10201521520 10586767360 1286294768 13.570 100 81 164 0 1320672032 16602432 17774832
23:20:00 11096 8192000000 10217667440 10603642880 1298977328 13.686 100 77 152 1 1329267504 13905984 16384192
23:21:12 3879 8192000000 10197040432 10608144384 1307999424 13.768 100 38 77 0 1323902688 6988544 8914720
23:22:19 9766 8192000000 10199670768 10614472704 1316688720 13.847 100 18 36 0 1326449376 6072752 3687904
23:23:31 17410 8192000000 10214970576 10634178560 1327776976 13.947 100 14 28 1 1336427520 5030704 3619840
23:24:49 45781 8192000000 10248590752 10652360704 1337792320 14.038 100 74 150 2 1361760752 6425408 17543024
23:26:35 6591 8192000000 10242801056 10663436288 1342991616 14.084 100 24 48 0 1358740368 10569328 5179424
23:27:43 11981 8192000000 10229948496 10652282880 1328884144 13.957 99 22 44 1 1340047216 6426512 4736560
23:28:56 11730 8192000000 10207994960 10619678720 1278795408 13.502 96 20 40 0 1288784400 5504048 4484944
23:30:10 7299 8192000000 10194069152 10612621312 1280290256 13.516 97 26 52 1 1291590144 5577440 5722448
23:31:19 6117 8192000000 10128968992 10522435584 1207443712 12.845 96 42 83 2 1223960656 6224352 10292592
23:32:26 7690 8192000000 10143176256 10546978816 1241136400 13.157 98 22 44 1 1251930688 5446144 5348144
23:33:34 6212 8192000000 10170663952 10564034560 1253533232 13.271 99 34 68 3 1267220416 4445248 9241936
23:34:40 21419 8192000000 10170506816 10586591232 1282128560 13.532 100 21 41 0 1292420224 5743536 4548128
23:36:05 10456 8192000000 10192678992 10607370240 1298613920 13.683 100 43 85 1 1315665776 5592224 11459632
23:37:20 9878 8192000000 10203304144 10619084800 1310833856 13.794 100 15 30 1 1319207072 5173392 3199824
23:38:32 31948 8192000000 10249475776 10660151296 1341194672 14.068 100 47 93 1 1358745568 6388256 11162640
23:40:07 59514 8192000000 10262319440 10684071936 1359425904 14.232 100 40 80 0 1375707952 7523520 8758528
23:42:10 26728 8192000000 10295891008 10713485312 1376207120 14.383 100 51 98 0 1398385616 11273632 10904864
23:43:46 29171 8192000000 10309515984 10738151424 1397846560 14.576 100 34 67 0 1423429056 16383152 9199344
23:45:24 25663 8192000000 10325627120 10750959616 1408625968 14.672 100 52 103 2 1438059408 16790464 12642976
23:47:00 14686 8192000000 10337396016 10760249344 1415033568 14.729 100 63 125 0 1447638272 17413200 15191504
23:48:22 4443 8192000000 10334360176 10758254592 1406629056 14.654 100 69 136 3 1444048592 20509344 16910192
23:49:33 2486 8192000000 10341648800 10763210752 1412874672 14.709 99 68 135 2 1454402576 25653456 15874448
23:50:40 2269 8192000000 10339241424 10754793472 1398037984 14.578 100 68 135 2 1438127648 24327360 15762304
23:51:45 4126 8192000000 10333522304 10748485632 1394094896 14.542 100 65 131 0 1435791456 24734208 16962352
23:52:52 4965 8192000000 10331851984 10744614912 1373512240 14.359 99 82 164 2 1424905008 31607872 19784896
23:54:02 3645 8192000000 10324076032 10738065408 1363972784 14.273 98 61 120 1 1416788256 36762064 16053408
23:55:09 4609 8192000000 10345761328 10759176192 1389275648 14.499 100 61 120 2 1445586128 41227728 15082752
23:56:23 5596 8192000000 10351151232 10764709888 1391618416 14.520 100 61 119 3 1454586048 47163216 15804416
23:57:31 8384 8192000000 10335547664 10768904192 1396029328 14.560 100 38 76 0 1448705152 42242496 10433328
23:58:46 8903 8192000000 10342711168 10772545536 1394740592 14.548 100 42 83 0 1448482000 42332096 11409312
00:00:02 23397 8192000000 10306293360 10745270272 1374365584 14.366 100 28 56 2 1420108544 38248512 7494448
00:01:30 13929 8192000000 10307693888 10737504256 1362421648 14.259 100 27 53 1 1407568128 38083456 7063024
00:02:45 25527 8192000000 10295923312 10733035520 1338554320 14.044 99 22 44 0 1392493856 47045376 6894160
00:04:20 10652 8192000000 10295067952 10735243264 1343082784 14.085 98 24 48 0 1391801648 42102224 6616640
00:05:48 25202 8192000000 10294215696 10736742400 1364775008 14.280 99 23 46 2 1390512880 19943232 5794640
00:07:16 65365 8192000000 10269442224 10701033472 1326708128 13.937 98 11 22 1 1355881104 26410768 2762208
00:09:24 60825 8192000000 10282129920 10742398976 1356484608 14.206 99 7 14 0 1376635408 18524480 1626320
00:11:27 65886 8192000000 10258846784 10687270912 1322598928 13.900 96 30 60 1 1338569264 9295344 6674992
00:14:31 111922 8192000000 10290322032 10743345152 1365204944 14.284 99 26 52 0 1384420960 13621520 5594496
00:17:23 36809 8192000000 10304328000 10745778176 1387540128 14.484 100 47 94 0 1410464992 11658064 11266800
00:19:01 64705 8192000000 10294207440 10744639488 1381947824 14.434 99 10 20 0 1398108752 13837856 2323072
00:21:18 26113 8192000000 10291784256 10742693888 1371174000 14.338 99 46 92 0 1399658464 17766112 10718352
00:22:50 49189 8192000000 10299578656 10759507968 1399286944 14.589 100 13 26 1 1416159600 13606896 3265760
00:25:49 37043 8192000000 10290323984 10743042048 1351242464 14.159 96 14 28 0 1364990912 9964512 3783936
00:28:56 58187 8192000000 10291495504 10744578048 1368153344 14.310 98 16 32 0 1378639776 7150096 3336336
00:31:10 79986 8192000000 10269211360 10739384320 1367315936 14.303 99 16 32 1 1382975616 12170192 3489488
00:33:42 63448 8192000000 10268457200 10723921920 1331636816 13.982 96 10 19 0 1342315184 8677776 2000592
00:35:48 22885 8192000000 10272418784 10723373056 1348815120 14.137 98 41 82 1 1370773984 12681600 9277264
00:37:13 76179 8192000000 10286594064 10755190784 1358894336 14.227 99 19 38 2 1383862000 20004576 4963088
00:39:46 49879 8192000000 10246497520 10688159744 1267020272 13.394 92 15 30 1 1277463072 7254560 3188240
00:41:37 65003 8192000000 10279609184 10726096896 1306975344 13.759 95 24 48 2 1325063856 12741216 5347296
00:43:47 52469 8192000000 10291975632 10732298240 1349275328 14.141 98 28 56 1 1368384144 13205184 5903632
00:45:42 75253 8192000000 10272597264 10740797440 1286465616 13.572 93 38 75 1 1312428784 17815840 8147328
00:47:59 35738 8192000000 10276920656 10719948800 1337777088 14.037 98 22 44 0 1353320992 10977344 4566560
00:49:39 77310 8192000000 10246646688 10688995328 1308607264 13.773 96 27 54 1 1329707104 14518224 6581616
00:52:11 23429 8192000000 10253419760 10694098944 1340313616 14.060 99 31 62 1 1356103232 8593040 7196576
00:54:00 47000 8192000000 10229918528 10687729664 1327783024 13.947 99 11 22 0 1339028000 8862640 2382336
00:55:47 59154 8192000000 10249531360 10708090880 1355981264 14.201 100 16 32 1 1367362736 7527952 3853520
00:57:50 94384 8192000000 10270845376 10725498880 1335056960 14.013 97 18 36 0 1351104656 12085904 3961792
01:00:38 53947 8192000000 10279946000 10727444480 1354974752 14.192 99 15 30 0 1371009376 12235120 3799504
01:02:40 70244 8192000000 10271015392 10716532736 1313204656 13.815 96 18 36 0 1328009440 10703728 4101056
01:04:56 51864 8192000000 10290216176 10743259136 1383887344 14.451 100 21 42 1 1404415680 15935088 4593248
01:06:49 62230 8192000000 10298100960 10748461056 1374296560 14.366 99 31 61 1 1395957728 14157616 7503552
01:09:54 130483 8192000000 10289337968 10734174208 1368986512 14.318 98 11 22 0 1380480960 9414256 2080192
01:13:19 59604 8192000000 10301059744 10755104768 1371399616 14.340 98 32 64 0 1392951040 14491984 7059440
01:15:19 67765 8192000000 10280630000 10745921536 1384129312 14.453 99 12 24 0 1399191168 12093744 2968112
01:18:15 75320 8192000000 10270499600 10722660352 1339907440 14.057 97 18 36 0 1349566128 5530784 4127904
01:20:35 61154 8192000000 10267773872 10718773248 1309509312 13.782 95 10 20 1 1325700592 13781664 2409616
01:23:21 33289 8192000000 10279791808 10728517632 1358420912 14.223 99 26 52 3 1378896640 14467408 6008320
01:25:04 45855 8192000000 10274640256 10719100928 1368968064 14.318 100 17 34 1 1389605696 16737232 3900400
01:26:53 31458 8192000000 10266103872 10701471744 1316683456 13.847 97 49 98 0 1338302368 11314864 10304048
01:28:28 68025 8192000000 10269552864 10708209664 1281478784 13.527 94 16 32 1 1294517648 9588384 3450480
01:30:37 51636 8192000000 10266557120 10723794944 1359651568 14.234 100 7 14 0 1374648128 13514528 1482032
01:32:28 55028 8192000000 10268993392 10721206272 1339010416 14.048 98 30 61 1 1358045824 12142544 6892864
01:34:24 87898 8192000000 10250856752 10701406208 1318924224 13.867 97 27 54 1 1333152752 7346512 6882016
01:36:52 78671 8192000000 10249123312 10705326080 1328917776 13.957 98 14 28 1 1349058080 16783952 3356352

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-05-2022 01:40:55 to 22-05-2022 01:41:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-05-2022 01:41:11 to 22-05-2022 01:41:13.

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          247412
MON$OLDEST_ACTIVE               247739
MON$OLDEST_SNAPSHOT             247739
MON$NEXT_TRANSACTION            247739
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                       2028640
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.33593 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33593 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33593 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33593 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 ---
:181: 
:182: mscs-test-161.budzdorov.ru    Sun May 22 01:38:50 2022
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:184: 
--- end of diff output ---

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

22.05.22 01:41:14. 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-05-22 01:41:16 No severe PSQL-related problems occured
22.05.22 01:41:18. End of report.