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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-28 01:38:29
DTS_END                         2022-12-28 01:38:29.6920
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/56002
TRN_ID                          248929
ATT_ID                          720
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: 9631.09 20027 1155731
customer order: creation 181.18 318 21742
customer order: refuse 16.24 298 1949
order to supplier: creation 47.75 1264 5730
order to supplier: removal 3.49 92655 419
invoice (draft): creation 48.52 3173 5822
invoice (draft): removal 55.10 739 6612
invoice accept: apply 44.60 17705 5352
invoice accept: cancel 49.92 736 5990
customer reserve: creation 4038.80 178 484656
customer reserve: removal 4836.58 52 580389
realization accept: apply 87.03 180 10443
realization accept: cancel 88.22 111 10586
payment from customer: creation 61.42 100 7370
payment from customer: removal 6.05 78 726
payment to supplier: creation 50.46 124 6055
payment to supplier: removal 3.52 70 422
service: total inventory turnovers 4.68 1186 562
service: total monetary turnovers 6.63 56 796
service: aggregate perf. data 0.88 21124 106
service: refresh index statistics 0.03 260386 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 28-12-2022 01:39:27 to 28-12-2022 01:39:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5098
TEST_TIME 2 5921
TEST_TIME 3 5251
TEST_TIME 4 5787
TEST_TIME 5 5585
TEST_TIME 6 5796
TEST_TIME 7 5428
TEST_TIME 8 5384
TEST_TIME 9 5555
TEST_TIME 10 6023
TEST_TIME 11 6261
TEST_TIME 12 6555
TEST_TIME 13 6772
TEST_TIME 14 7139
TEST_TIME 15 7418
TEST_TIME 16 7465
TEST_TIME 17 8263
TEST_TIME 18 8522
TEST_TIME 19 8407
TEST_TIME 20 8657
TEST_TIME 21 8620
TEST_TIME 22 8593
TEST_TIME 23 9195
TEST_TIME 24 10045
TEST_TIME 25 10121
TEST_TIME 26 9839
TEST_TIME 27 9858
TEST_TIME 28 9640
TEST_TIME 29 9728
TEST_TIME 30 10091
TEST_TIME 31 10805
TEST_TIME 32 10673
TEST_TIME 33 10485
TEST_TIME 34 10470
TEST_TIME 35 10452
TEST_TIME 36 10379
TEST_TIME 37 10416
TEST_TIME 38 10438
TEST_TIME 39 10567
TEST_TIME 40 10485
TEST_TIME 41 10514
TEST_TIME 42 10438
TEST_TIME 43 10351
TEST_TIME 44 10154
TEST_TIME 45 10081
TEST_TIME 46 10142
TEST_TIME 47 10072
TEST_TIME 48 9932
TEST_TIME 49 9949
TEST_TIME 50 9923
TEST_TIME 51 9818
TEST_TIME 52 9981
TEST_TIME 53 9860
TEST_TIME 54 9782
TEST_TIME 55 9701
TEST_TIME 56 9594
TEST_TIME 57 9615
TEST_TIME 58 9578
TEST_TIME 59 9682
TEST_TIME 60 9651
TEST_TIME 61 9635
TEST_TIME 62 9804
TEST_TIME 63 9816
TEST_TIME 64 9766
TEST_TIME 65 9911
TEST_TIME 66 9880
TEST_TIME 67 9931
TEST_TIME 68 9876
TEST_TIME 69 9902
TEST_TIME 70 10027
TEST_TIME 71 10048
TEST_TIME 72 10053
TEST_TIME 73 9988
TEST_TIME 74 9893
TEST_TIME 75 9903
TEST_TIME 76 9871
TEST_TIME 77 9852
TEST_TIME 78 9834
TEST_TIME 79 9760
TEST_TIME 80 9696
TEST_TIME 81 9791
TEST_TIME 82 9745
TEST_TIME 83 9686
TEST_TIME 84 9643
TEST_TIME 85 9604
TEST_TIME 86 9569
TEST_TIME 87 9569
TEST_TIME 88 9548
TEST_TIME 89 9503
TEST_TIME 90 9492
TEST_TIME 91 9482
TEST_TIME 92 9550
TEST_TIME 93 9619
TEST_TIME 94 9608
TEST_TIME 95 9677
TEST_TIME 96 9672
TEST_TIME 97 9702
TEST_TIME 98 9736
TEST_TIME 99 9715
TEST_TIME 100 9766
TEST_TIME 101 9770
TEST_TIME 102 9819
TEST_TIME 103 9783
TEST_TIME 104 9751
TEST_TIME 105 9711
TEST_TIME 106 9659
TEST_TIME 107 9666
TEST_TIME 108 9646
TEST_TIME 109 9652
TEST_TIME 110 9619
TEST_TIME 111 9621
TEST_TIME 112 9614
TEST_TIME 113 9620
TEST_TIME 114 9616
TEST_TIME 115 9598
TEST_TIME 116 9604
TEST_TIME 117 9672
TEST_TIME 118 9631
TEST_TIME 119 9631
TEST_TIME 120 9632

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-12-2022 01:39:31 to 28-12-2022 01:39:35.

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 1604690 1604690 0 0.00 0 22764 6 0 0
doc_list_biud 1604690 1604690 0 0.00 0 32270 25 0 0
sp_add_invoice_to_stock 5446 5352 94 1.72 23 207380 17705 0 94
sp_cancel_adding_invoice 5990 5990 0 0.00 4 61217 737 0 0
sp_cancel_client_order 1949 1949 0 0.00 4 10021 298 0 0
sp_cancel_customer_reserve 580389 580389 0 0.00 0 28763 52 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 2 3354 78 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 4234 71 0 0
sp_cancel_supplier_invoice 6620 6612 8 0.12 1 28844 740 0 8
sp_cancel_supplier_order 419 419 0 0.00 5 947870 92655 0 0
sp_cancel_write_off 10586 10586 0 0.00 1 19031 111 0 0
sp_client_order 21742 21742 0 0.00 3 15391 318 0 0
sp_customer_reserve 484656 484656 0 0.00 0 144649 179 0 0
sp_fill_shopping_cart 33264 33264 0 0.00 1 19199 35 0 0
sp_fill_shopping_cart_clo_res 484653 484653 0 0.00 0 15352 14 0 0
sp_get_clo_for_invoice 5348 5348 0 0.00 0 30385 310 0 0
sp_kill_cost_storno 17724 17724 0 0.00 0 7759 48 0 0
sp_kill_qstorno_ret_qs2qd 1178395 1178395 0 0.00 0 28832 29 0 0
sp_kill_qty_storno 621735 621735 0 0.00 0 32269 63 0 0
sp_lock_selected_doc 21860 21860 0 0.00 0 8911 6 0 0
sp_make_cost_storno 29215 29215 0 0.00 0 12843 52 0 0
sp_multiply_rows_for_pdistr 29215 29215 0 0.00 0 9456 27 0 0
sp_multiply_rows_for_qdistr 21742 21742 0 0.00 0 12882 142 0 0
sp_pay_from_customer 7370 7370 0 0.00 4 10981 101 0 0
sp_pay_to_supplier 6055 6055 0 0.00 4 14018 124 0 0
sp_payment_common 13425 13425 0 0.00 2 14006 24 0 0
sp_qd_handle_on_cancel_clo 1949 1949 0 0.00 0 10012 279 0 0
sp_qd_handle_on_invoice_upd_sts 11337 11337 0 0.00 0 32268 318 0 0
sp_qd_handle_on_reserve_upd_sts 10443 10443 0 0.00 0 16891 67 0 0
sp_reserve_write_off 10443 10443 0 0.00 5 17153 180 0 0
sp_supplier_invoice 5822 5822 0 0.00 6 73243 3173 0 0
sp_supplier_order 5730 5730 0 0.00 5 25673 1264 0 0
srv_aggregate_perf_data 106 106 0 0.00 2506 210199 21125 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1635 145314 46194 0 0
srv_find_qd_qs_mism 1105942 1105942 0 0.00 0 22762 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 34 48888 1187 0 0
srv_make_money_saldo 796 796 0 0.00 0 13853 56 0 0
srv_recalc_idx_stat 4 4 0 0.00 175744 364538 260387 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5352 5352 0 0.00 0 12432 147 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 0 6053 99 0 0
t$perf-norm:sp_cancel_client_order 1949 1949 0 0.00 0 7493 15 0 0
t$perf-norm:sp_cancel_customer_reserve 734 734 0 0.00 0 3510 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 5592 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 306 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1004 12 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 21677 1493 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 4045 9 0 0
t$perf-norm:sp_client_order 21742 21742 0 0.00 0 16008 14 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 3541 6 0 0
t$perf-norm:sp_pay_from_customer 7370 7370 0 0.00 0 11151 13 0 0
t$perf-norm:sp_pay_to_supplier 6055 6055 0 0.00 0 6305 9 0 0
t$perf-norm:sp_reserve_write_off 10443 10443 0 0.00 0 6754 10 0 0
t$perf-norm:sp_supplier_invoice 5822 5822 0 0.00 0 5952 16 0 0
t$perf-norm:sp_supplier_order 5730 5730 0 0.00 0 8213 16 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 126 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1739 28 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 1442 24 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 6310 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 10870 10682 188 1.72 0 4690 19 0 188
v_all_customers 39026 39026 0 0.00 0 178 1 0 0
v_all_suppliers 31298 31298 0 0.00 0 110 1 0 0
v_all_wares 43478 43478 0 0.00 0 108 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 1863 10 0 0
v_cancel_client_order 3898 3898 0 0.00 0 2692 4 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 1670 7 0 0
v_cancel_customer_reserve 1468 1468 0 0.00 0 742 4 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 3173 7 0 0
v_cancel_supplier_invoice 808 792 16 1.98 0 7926 38 0 16
v_cancel_supplier_order 838 838 0 0.00 0 4994 17 0 0
v_cancel_write_off 1990 1990 0 0.00 0 7021 18 0 0
v_max_id_clo_ord 5723 5723 0 0.00 0 2445 2 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 2303 20 0 0
v_max_id_ord_sup 5802 5802 0 0.00 0 3571 5 0 0
v_max_non_paid_invoice 1931 1931 0 0.00 0 497 2 0 0
v_max_non_paid_realizn 4103 4103 0 0.00 0 2106 4 0 0
v_min_id_clo_ord 5723 5723 0 0.00 0 2608 5 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 2625 6 0 0
v_min_id_ord_sup 5802 5802 0 0.00 0 5616 9 0 0
v_min_non_paid_invoice 6055 6055 0 0.00 0 14001 19 0 0
v_min_non_paid_realizn 7370 7370 0 0.00 0 4330 17 0 0
v_our_firm 10986 10986 0 0.00 0 18 1 0 0
v_reserve_write_off 20886 20886 0 0.00 0 5203 8 0 0
x_lock_depdocs_on_canc_invoice 12206 12206 0 0.00 0 32982 111 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 11962 352 0 0
x_make_qstorno_1000_1200 5730 5730 0 0.00 1 25663 1188 0 0
x_make_qstorno_1200_2000 5822 5822 0 0.00 2 65508 3045 0 0
x_make_qstorno_2100_3300 484656 484656 0 0.00 0 144598 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-12-2022 01:39:36 to 28-12-2022 01:39:40.

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:33 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:35 36999 8192000000 9912028592 10225127424 994796192 10.828 100 93 186 0 1025734016 6600400 24337424
23:11:17 19878 8192000000 9952352832 10280001536 1038035424 11.246 100 86 172 0 1067904592 8334752 21534416
23:12:45 13682 8192000000 9994337056 10329587712 1077554400 11.624 100 86 172 0 1108437776 9717248 21166128
23:14:02 7416 8192000000 10034029936 10383495168 1124470016 12.069 100 82 162 0 1154810848 11014272 19326560
23:15:15 7382 8192000000 10084985792 10444681216 1169743904 12.494 100 77 152 0 1198741856 11420976 17576976
23:16:25 3687 8192000000 10130127808 10496352256 1211108368 12.879 100 84 168 0 1243468480 14099376 18260736
23:17:30 1596 8192000000 10167058304 10541957120 1250155776 13.240 100 88 173 1 1282923760 12752832 20015152
23:18:33 1403 8192000000 10185699600 10569424896 1277255664 13.488 100 76 152 0 1306000976 12339104 16406208
23:19:36 1550 8192000000 10214499904 10605920256 1310357008 13.789 100 82 164 2 1340628512 12094064 18177440
23:20:38 6835 8192000000 10204009888 10616864768 1317221776 13.852 100 37 74 1 1333053728 7351536 8480416
23:21:49 10599 8192000000 10212949264 10634309632 1330765392 13.974 100 23 45 0 1341193472 5619872 4808208
23:23:00 7170 8192000000 10222323152 10644267008 1338880832 14.047 100 21 42 0 1349827072 6540400 4405840
23:24:08 52913 8192000000 10234309328 10656079872 1344371696 14.097 100 38 71 2 1358148240 4887168 8889376
23:26:03 6855 8192000000 10239050608 10664329216 1348547696 14.134 100 19 37 1 1358695824 5968608 4179520
23:27:11 6034 8192000000 10218481568 10635284480 1310704112 13.792 98 22 44 0 1321583152 5927200 4951840
23:28:18 54904 8192000000 10198537040 10606530560 1293336816 13.635 99 44 88 0 1309833216 5917024 10579376
23:30:14 6224 8192000000 10164610528 10561871872 1257634304 13.308 98 30 59 1 1270117584 5905184 6578096
23:31:21 5446 8192000000 10151524576 10550718464 1244337088 13.186 99 24 47 0 1255564640 6026656 5200896
23:32:27 9742 8192000000 10157766464 10567016448 1267450048 13.398 100 18 36 0 1277700160 5750720 4499392
23:33:39 27376 8192000000 10151943664 10559672320 1253496240 13.270 98 26 54 0 1265045968 5797472 5752256
23:35:07 5041 8192000000 10185497136 10592395264 1289618112 13.601 100 34 68 0 1304297056 7173920 7505024
23:36:13 8545 8192000000 10194541312 10608132096 1302295072 13.716 100 23 46 0 1313235808 5272160 5668576
23:37:22 9341 8192000000 10196059168 10612682752 1307957584 13.768 100 19 39 0 1318044368 5612736 4474048
23:38:33 39893 8192000000 10221504672 10640056320 1328159072 13.951 100 33 67 0 1342172080 6236528 7776480
23:40:21 8749 8192000000 10268351296 10680340480 1353152368 14.176 100 53 106 0 1376205536 11633440 11419728
23:41:44 30851 8192000000 10287764016 10705014784 1372446480 14.349 100 46 91 1 1397522240 14745136 10330624
23:43:21 35745 8192000000 10286363136 10706051072 1357089936 14.211 98 38 73 1 1380212224 14524320 8597968
23:45:03 27348 8192000000 10287131056 10712633344 1370268336 14.329 99 38 76 0 1393883536 14661536 8953664
23:46:33 13074 8192000000 10313915696 10735542272 1391915344 14.523 100 57 109 0 1423159920 18424384 12820192
23:47:50 10274 8192000000 10326565824 10735648768 1390337936 14.509 100 65 128 1 1430447536 24899952 15209648
23:49:06 9947 8192000000 10317868448 10735779840 1372302576 14.348 98 51 101 0 1409695600 25754992 11638032
23:50:18 1812 8192000000 10340853232 10753548288 1395160624 14.552 100 72 144 0 1447729904 34815168 17754112
23:51:23 5264 8192000000 10336706512 10749112320 1389828336 14.504 100 64 128 1 1444394384 38611376 15954672
23:52:36 4942 8192000000 10332745520 10742984704 1383688080 14.450 100 66 128 0 1440472704 40709888 16074736
23:53:46 4609 8192000000 10297018096 10709102592 1354070416 14.184 99 53 106 1 1400712784 33562640 13079728
23:54:57 2270 8192000000 10328793744 10738102272 1377543952 14.395 100 85 168 1 1434858784 36810512 20504320
23:56:05 5670 8192000000 10308256592 10731188224 1371845584 14.344 99 42 85 0 1412456784 29981600 10629600
23:57:17 12414 8192000000 10300072944 10724626432 1356060208 14.202 99 31 62 0 1391880016 27142624 8677184
23:58:34 6679 8192000000 10319986928 10745266176 1380475200 14.421 100 31 62 1 1420749840 31893920 8380720
23:59:55 8882 8192000000 10295114064 10709487616 1344813280 14.101 100 44 86 0 1395805472 40254304 10737888
00:01:15 8250 8192000000 10315070624 10734718976 1364338112 14.276 100 42 84 0 1419227024 43349152 11539760
00:02:42 1630 8192000000 10332198608 10747740160 1373135888 14.355 100 77 149 1 1429471296 35734544 20600864
00:03:46 80672 8192000000 10307431360 10737070080 1362795024 14.262 99 38 72 0 1403807376 31347936 9664416
00:06:12 26018 8192000000 10314863376 10758168576 1385965008 14.470 100 23 46 0 1425441280 33124496 6351776
00:07:38 29469 8192000000 10314067200 10762166272 1370868128 14.335 100 26 52 1 1420156432 41896016 7392288
00:09:15 72690 8192000000 10299335664 10733244416 1370989216 14.336 99 19 39 0 1392149664 16776368 4384080
00:11:30 87388 8192000000 10295974688 10733682688 1338097488 14.040 96 25 51 0 1355189520 11054624 6037408
00:14:00 69041 8192000000 10297018128 10739757056 1357646656 14.216 98 21 42 2 1377420656 14917600 4856400
00:16:10 30784 8192000000 10312677104 10760146944 1393843312 14.540 100 26 51 1 1413508960 14081024 5584624
00:17:41 81034 8192000000 10309050112 10763726848 1395131904 14.552 100 7 12 0 1413938032 17590912 1215216
00:20:06 58856 8192000000 10308568048 10759675904 1385829360 14.469 99 9 19 0 1395737856 7798192 2110304
00:22:06 89830 8192000000 10316150976 10770325504 1400258544 14.597 100 40 78 1 1422458000 13048592 9150864
00:24:36 64108 8192000000 10309298416 10768318464 1379156240 14.409 98 20 40 1 1393014464 9607392 4250832
00:26:58 14061 8192000000 10295807984 10746576896 1353763664 14.181 97 32 62 1 1374320272 13545024 7011584
00:28:20 44753 8192000000 10270065648 10713612288 1311144976 13.796 95 32 61 1 1326313184 8027664 7140544
00:30:13 41360 8192000000 10269528624 10741125120 1366079952 14.292 100 13 26 0 1388063136 18751728 3231456
00:31:56 145314 8192000000 10268065920 10726035456 1309217296 13.779 95 21 42 0 1321469280 7227712 5024272
00:35:33 42320 8192000000 10264509520 10729533440 1339933056 14.057 98 7 14 0 1351921680 10223024 1765600
00:38:28 55889 8192000000 10270359648 10716553216 1327663040 13.946 97 34 68 0 1350002560 14634192 7705328
00:40:38 54660 8192000000 10257118064 10711224320 1313333824 13.816 96 24 48 0 1331248880 12369616 5545440
00:42:39 35199 8192000000 10288388704 10741477376 1370012640 14.327 100 30 60 0 1389525568 13307664 6205264
00:45:01 41132 8192000000 10292608864 10738085888 1356277696 14.204 99 37 75 1 1380193088 15770544 8144848
00:46:44 12041 8192000000 10283673328 10714722304 1325254816 13.924 97 43 87 0 1345105120 10910448 8939856
00:47:57 63232 8192000000 10284515008 10748542976 1360143392 14.239 99 16 33 0 1382888304 19108256 3636656
00:50:09 106724 8192000000 10265517296 10720448512 1342220768 14.077 98 8 16 0 1357630480 13699424 1710288
00:53:51 109985 8192000000 10269582640 10738962432 1334365136 14.007 97 11 23 0 1353295008 16128816 2801056
00:56:48 67101 8192000000 10284447664 10737885184 1359078496 14.229 98 18 35 0 1373354624 9956960 4319168
00:58:56 56579 8192000000 10262304272 10739281920 1365713312 14.289 100 10 20 0 1385539056 17111360 2714384
01:00:52 61674 8192000000 10268249984 10733809664 1374138128 14.364 100 8 16 0 1384681728 8545520 1998080
01:02:55 75353 8192000000 10251981280 10703761408 1326244352 13.933 96 28 57 0 1340785024 8289232 6251440
01:05:27 63632 8192000000 10272727968 10740715520 1365134192 14.283 99 14 28 0 1377609776 9365984 3109600
01:07:31 69944 8192000000 10307815712 10766487552 1386197360 14.472 99 18 36 0 1403560304 13099344 4263600
01:09:41 61277 8192000000 10320797216 10782691328 1397078928 14.569 99 20 40 0 1416956176 15190368 4686880
01:11:52 34547 8192000000 10323695072 10774474752 1392964128 14.532 99 32 65 1 1410356320 10014576 7377616
01:13:26 44263 8192000000 10304383584 10764615680 1358436608 14.223 97 20 40 0 1375357840 12762416 4158816
01:15:12 39860 8192000000 10303296736 10756493312 1373917728 14.362 99 25 50 0 1391082000 11856880 5307392
01:16:52 78256 8192000000 10300414416 10753814528 1350034400 14.148 97 17 34 0 1364585792 10824256 3727136
01:19:15 70726 8192000000 10282073088 10751823872 1325712112 13.928 96 7 14 0 1347545856 20170320 1663424
01:21:26 10947 8192000000 10284806480 10722893824 1352897568 14.174 99 40 80 0 1372224544 10943392 8383584
01:22:45 99707 8192000000 10272611280 10720362496 1306438400 13.754 95 19 39 0 1320324512 9455520 4430592
01:25:29 91787 8192000000 10267544992 10736943104 1340616624 14.063 98 12 24 1 1366106208 22430608 3058976
01:28:32 35322 8192000000 10267039488 10724773888 1339308992 14.051 98 25 50 0 1362008864 16674832 6025040
01:30:13 97130 8192000000 10270466272 10740981760 1366756240 14.298 100 15 30 0 1383945616 13278064 3911312
01:32:50 70610 8192000000 10296777200 10754256896 1355791648 14.200 98 13 26 0 1374570336 15674320 3104368
01:35:07 58325 8192000000 10258325600 10709680128 1309189312 13.779 96 9 17 1 1321649152 10867136 1592704
01:37:13 28251 8192000000 10263421008 10705608704 1323558464 13.909 98 36 72 0 1344674144 13328960 7786720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-12-2022 01:39:42 to 28-12-2022 01:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-12-2022 01:39:58 to 28-12-2022 01:40:00.

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          245760
MON$OLDEST_ACTIVE               249179
MON$OLDEST_SNAPSHOT             249179
MON$NEXT_TRANSACTION            249179
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                       2051232
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:779: 
:780: mscs-test-161.budzdorov.ru    Wed Dec 28 01:38:31 2022
:781:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:782: 
:783: 
:784: mscs-test-161.budzdorov.ru    Wed Dec 28 01:38:33 2022
:785:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:786: 
:787: 
:788: mscs-test-161.budzdorov.ru    Wed Dec 28 01:38:39 2022
:789:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:790: 
--- end of diff output ---

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

28.12.22 01:40:01. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-12-28 01:40:03 No severe PSQL-related problems occured
28.12.22 01:40:05. End of report.