Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.11.2021 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 16881336 kB
MemAvailable 31670056 kB
Buffers 12 kB
Cached 14842872 kB
SwapCached 0 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.8
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 2021-11-11 22:37:47
DTS_END                         2021-11-11 22:37:47.2550
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/44102
TRN_ID                          240830
ATT_ID                          682
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: 9454.01 23295 1134481
customer order: creation 172.88 348 20745
customer order: refuse 14.83 354 1779
order to supplier: creation 46.60 1246 5592
order to supplier: removal 3.68 95701 441
invoice (draft): creation 46.13 3237 5535
invoice (draft): removal 54.22 738 6506
invoice accept: apply 42.88 18206 5146
invoice accept: cancel 48.78 823 5853
customer reserve: creation 3910.05 183 469206
customer reserve: removal 4814.65 57 577758
realization accept: apply 83.67 162 10040
realization accept: cancel 82.43 114 9891
payment from customer: creation 61.23 111 7348
payment from customer: removal 5.85 81 702
payment to supplier: creation 50.38 133 6045
payment to supplier: removal 3.42 89 410
service: total inventory turnovers 4.63 1141 556
service: total monetary turnovers 6.82 23 818
service: aggregate perf. data 0.88 22749 106
service: refresh index statistics 0.03 320395 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-11-2021 22:39:12 to 11-11-2021 22:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4174
TEST_TIME 2 4251
TEST_TIME 3 4460
TEST_TIME 4 5248
TEST_TIME 5 5744
TEST_TIME 6 5425
TEST_TIME 7 5860
TEST_TIME 8 5997
TEST_TIME 9 6409
TEST_TIME 10 7085
TEST_TIME 11 7832
TEST_TIME 12 7406
TEST_TIME 13 7218
TEST_TIME 14 7347
TEST_TIME 15 7382
TEST_TIME 16 7473
TEST_TIME 17 7328
TEST_TIME 18 7174
TEST_TIME 19 7386
TEST_TIME 20 7745
TEST_TIME 21 7617
TEST_TIME 22 8453
TEST_TIME 23 8774
TEST_TIME 24 8831
TEST_TIME 25 9108
TEST_TIME 26 9501
TEST_TIME 27 9964
TEST_TIME 28 10821
TEST_TIME 29 10825
TEST_TIME 30 10577
TEST_TIME 31 10744
TEST_TIME 32 10648
TEST_TIME 33 10442
TEST_TIME 34 10183
TEST_TIME 35 10148
TEST_TIME 36 10177
TEST_TIME 37 10293
TEST_TIME 38 10123
TEST_TIME 39 10077
TEST_TIME 40 9987
TEST_TIME 41 9918
TEST_TIME 42 10015
TEST_TIME 43 9942
TEST_TIME 44 9854
TEST_TIME 45 9838
TEST_TIME 46 9766
TEST_TIME 47 9719
TEST_TIME 48 9801
TEST_TIME 49 9737
TEST_TIME 50 9811
TEST_TIME 51 9816
TEST_TIME 52 9825
TEST_TIME 53 9908
TEST_TIME 54 9936
TEST_TIME 55 9897
TEST_TIME 56 9913
TEST_TIME 57 9916
TEST_TIME 58 9868
TEST_TIME 59 9937
TEST_TIME 60 10053
TEST_TIME 61 10024
TEST_TIME 62 9966
TEST_TIME 63 9834
TEST_TIME 64 9841
TEST_TIME 65 9855
TEST_TIME 66 9780
TEST_TIME 67 9765
TEST_TIME 68 9781
TEST_TIME 69 9823
TEST_TIME 70 9782
TEST_TIME 71 9703
TEST_TIME 72 9736
TEST_TIME 73 9778
TEST_TIME 74 9762
TEST_TIME 75 9832
TEST_TIME 76 9829
TEST_TIME 77 9811
TEST_TIME 78 9932
TEST_TIME 79 9927
TEST_TIME 80 9939
TEST_TIME 81 9920
TEST_TIME 82 9851
TEST_TIME 83 9817
TEST_TIME 84 9799
TEST_TIME 85 9729
TEST_TIME 86 9747
TEST_TIME 87 9723
TEST_TIME 88 9674
TEST_TIME 89 9651
TEST_TIME 90 9603
TEST_TIME 91 9525
TEST_TIME 92 9488
TEST_TIME 93 9510
TEST_TIME 94 9507
TEST_TIME 95 9551
TEST_TIME 96 9549
TEST_TIME 97 9647
TEST_TIME 98 9603
TEST_TIME 99 9561
TEST_TIME 100 9622
TEST_TIME 101 9661
TEST_TIME 102 9687
TEST_TIME 103 9691
TEST_TIME 104 9723
TEST_TIME 105 9724
TEST_TIME 106 9697
TEST_TIME 107 9753
TEST_TIME 108 9779
TEST_TIME 109 9693
TEST_TIME 110 9714
TEST_TIME 111 9669
TEST_TIME 112 9635
TEST_TIME 113 9594
TEST_TIME 114 9563
TEST_TIME 115 9557
TEST_TIME 116 9508
TEST_TIME 117 9484
TEST_TIME 118 9468
TEST_TIME 119 9448
TEST_TIME 120 9459

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-11-2021 22:39:16 to 11-11-2021 22:39:19.

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 1570021 1570021 0 0.00 0 13339 6 0 0
doc_list_biud 1570021 1570021 0 0.00 0 36176 27 0 0
sp_add_invoice_to_stock 5260 5146 114 2.16 32 165235 18207 0 114
sp_cancel_adding_invoice 5853 5853 0 0.00 4 111290 824 0 0
sp_cancel_client_order 1779 1779 0 0.00 5 14197 355 0 0
sp_cancel_customer_reserve 577758 577758 0 0.00 1 36178 58 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 2 6711 81 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 4772 90 0 0
sp_cancel_supplier_invoice 6517 6506 11 0.16 2 14882 738 0 11
sp_cancel_supplier_order 441 441 0 0.00 7 896657 95702 0 0
sp_cancel_write_off 9891 9891 0 0.00 1 15054 115 0 0
sp_client_order 20745 20745 0 0.00 3 18573 349 0 0
sp_customer_reserve 469206 469206 0 0.00 0 83712 184 0 0
sp_fill_shopping_cart 31872 31872 0 0.00 1 8440 35 0 0
sp_fill_shopping_cart_clo_res 469204 469204 0 0.00 0 15348 13 0 0
sp_get_clo_for_invoice 5138 5138 0 0.00 0 22078 306 0 0
sp_kill_cost_storno 16856 16856 0 0.00 0 12687 56 0 0
sp_kill_qstorno_ret_qs2qd 1172354 1172354 0 0.00 0 36174 31 0 0
sp_kill_qty_storno 617399 617399 0 0.00 0 36176 67 0 0
sp_lock_selected_doc 21098 21098 0 0.00 0 7345 6 0 0
sp_make_cost_storno 28564 28564 0 0.00 0 13319 51 0 0
sp_multiply_rows_for_pdistr 28564 28564 0 0.00 0 7451 28 0 0
sp_multiply_rows_for_qdistr 20745 20745 0 0.00 0 18508 149 0 0
sp_pay_from_customer 7348 7348 0 0.00 4 13909 111 0 0
sp_pay_to_supplier 6045 6045 0 0.00 5 15286 134 0 0
sp_payment_common 13393 13393 0 0.00 1 15222 28 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 14183 323 0 0
sp_qd_handle_on_invoice_upd_sts 10984 10984 0 0.00 0 22661 319 0 0
sp_qd_handle_on_reserve_upd_sts 10040 10040 0 0.00 0 12078 53 0 0
sp_reserve_write_off 10040 10040 0 0.00 6 12087 163 0 0
sp_supplier_invoice 5535 5535 0 0.00 6 74904 3237 0 0
sp_supplier_order 5592 5592 0 0.00 5 24350 1246 0 0
srv_aggregate_perf_data 106 106 0 0.00 2883 473393 22750 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2207 135491 43259 0 0
srv_find_qd_qs_mism 1086458 1086458 0 0.00 0 13274 6 0 0
srv_make_invnt_saldo 556 556 0 0.00 34 60863 1141 0 0
srv_make_money_saldo 818 818 0 0.00 1 1878 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 133128 616181 320396 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5146 5146 0 0.00 0 14979 173 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 7830 112 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 7753 16 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 2422 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 10802 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1910 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 748 15 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 20501 1688 0 0
t$perf-norm:sp_cancel_write_off 1040 1040 0 0.00 0 266 2 0 0
t$perf-norm:sp_client_order 20745 20745 0 0.00 0 6516 15 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 3114 8 0 0
t$perf-norm:sp_pay_from_customer 7348 7348 0 0.00 0 5253 9 0 0
t$perf-norm:sp_pay_to_supplier 6045 6045 0 0.00 0 10697 12 0 0
t$perf-norm:sp_reserve_write_off 10040 10040 0 0.00 0 12440 13 0 0
t$perf-norm:sp_supplier_invoice 5535 5535 0 0.00 0 5637 23 0 0
t$perf-norm:sp_supplier_order 5592 5592 0 0.00 0 6652 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 2929 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 1521 23 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 742 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 56 15 0 0
v_add_invoice_to_stock 10490 10262 228 2.17 0 7451 20 0 228
v_all_customers 38236 38236 0 0.00 0 59 1 0 0
v_all_suppliers 30984 30984 0 0.00 0 44 1 0 0
v_all_wares 41490 41490 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 3598 10 0 0
v_cancel_client_order 3558 3558 0 0.00 0 7620 9 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 4112 12 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 7461 9 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1885 12 0 0
v_cancel_supplier_invoice 880 858 22 2.50 0 3429 19 0 22
v_cancel_supplier_order 882 882 0 0.00 0 5442 16 0 0
v_cancel_write_off 2080 2080 0 0.00 0 4776 16 0 0
v_max_id_clo_ord 5592 5592 0 0.00 0 5641 3 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 3747 19 0 0
v_max_id_ord_sup 5535 5535 0 0.00 0 2767 4 0 0
v_max_non_paid_invoice 1680 1680 0 0.00 0 611 2 0 0
v_max_non_paid_realizn 3816 3816 0 0.00 0 6912 7 0 0
v_min_id_clo_ord 5592 5592 0 0.00 0 3566 7 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 3063 6 0 0
v_min_id_ord_sup 5535 5535 0 0.00 0 3081 4 0 0
v_min_non_paid_invoice 6045 6045 0 0.00 0 15219 20 0 0
v_min_non_paid_realizn 7348 7348 0 0.00 0 13897 20 0 0
v_our_firm 10318 10318 0 0.00 0 28 1 0 0
v_reserve_write_off 20080 20080 0 0.00 0 6523 6 0 0
x_lock_depdocs_on_canc_invoice 11930 11930 0 0.00 0 15096 118 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 23107 395 0 0
x_make_qstorno_1000_1200 5592 5592 0 0.00 1 24334 1167 0 0
x_make_qstorno_1200_2000 5535 5535 0 0.00 3 74885 3123 0 0
x_make_qstorno_2100_3300 469206 469206 0 0.00 0 83707 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-11-2021 22:39:20 to 11-11-2021 22:39:24.

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:07:51 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:53 29243 8192000000 9897938368 10209099776 978826144 10.673 100 87 174 0 1008489808 6084704 23578960
20:10:28 18271 8192000000 9937933312 10259021824 1014562528 11.019 100 86 172 0 1045299248 8387792 22348928
20:12:06 15170 8192000000 9983941072 10323050496 1068501120 11.538 100 77 153 2 1097154000 10024016 18628864
20:13:24 16293 8192000000 10052040624 10404724736 1139823888 12.214 100 79 158 0 1169319552 10930320 18565344
20:14:49 10667 8192000000 10100700064 10466807808 1192809536 12.710 100 84 167 1 1224840096 13138480 18892080
20:16:02 4342 8192000000 10145745776 10517655552 1227211776 13.028 100 87 174 2 1260426176 14023744 19190656
20:17:09 2775 8192000000 10168551472 10542276608 1254258656 13.277 100 93 185 0 1288381824 14149008 19974160
20:18:15 1467 8192000000 10198499952 10581585920 1278509264 13.499 100 90 181 0 1313197456 14662832 20025360
20:19:17 35159 8192000000 10195699920 10596012032 1292609680 13.628 100 59 118 0 1314714688 7807856 14297152
20:20:54 11313 8192000000 10201680752 10612482048 1308830784 13.775 100 37 75 0 1325368224 8449920 8087520
20:22:07 61120 8192000000 10235833376 10635333632 1320066464 13.877 100 48 97 1 1337323488 6079520 11177504
20:24:09 7286 8192000000 10221742240 10641952768 1329612384 13.964 100 26 52 0 1342353104 6150304 6590416
20:25:17 6594 8192000000 10224996096 10646818816 1323826032 13.911 99 30 60 2 1337809264 7138128 6845104
20:26:24 73862 8192000000 10197143552 10617102336 1283901792 13.549 96 32 64 2 1297521648 6196432 7423424
20:28:38 5927 8192000000 10187915936 10602106880 1276031824 13.477 97 23 46 1 1286670208 5223184 5415200
20:29:44 6745 8192000000 10145182496 10549952512 1208483392 12.855 95 19 38 0 1218583984 5623824 4476768
20:30:54 9557 8192000000 10131043232 10529214464 1234646864 13.097 99 30 57 3 1246604656 5172336 6785456
20:32:06 28899 8192000000 10140391808 10542362624 1231674656 13.070 98 21 42 0 1241792560 5646976 4470928
20:33:36 8536 8192000000 10162852816 10571177984 1263981632 13.367 99 20 39 0 1274647600 6653360 4012608
20:34:45 4815 8192000000 10182335344 10591268864 1291003712 13.613 100 45 91 1 1311746672 9478352 11264608
20:35:52 8489 8192000000 10193772944 10605727744 1301606560 13.710 100 22 44 1 1312084560 5432816 5045184
20:37:11 46912 8192000000 10226027792 10634825728 1321956304 13.894 100 37 74 1 1335491552 5802560 7732688
20:39:01 13695 8192000000 10245161760 10656952320 1341258256 14.069 100 48 95 0 1361062832 9466752 10337824
20:40:20 12769 8192000000 10264947376 10677628928 1356354560 14.205 100 57 114 1 1380594896 11739840 12500496
20:41:34 14565 8192000000 10273869696 10694598656 1366054576 14.292 100 34 67 2 1384819712 10912064 7853072
20:42:54 21016 8192000000 10288288224 10712223744 1380804336 14.424 100 46 92 1 1402932000 11850944 10276720
20:44:26 7918 8192000000 10307125648 10727374848 1394196656 14.543 100 67 134 4 1423636576 13629824 15810096
20:45:39 6292 8192000000 10303584528 10731024384 1390918608 14.514 100 51 102 0 1426022944 23342528 11761808
20:46:49 16372 8192000000 10317881696 10741874688 1395997040 14.559 100 47 95 0 1425757520 18553520 11206960
20:48:08 2676 8192000000 10329501280 10747932672 1404455056 14.635 100 76 152 0 1443005584 21088768 17461760
20:49:16 17085 8192000000 10321195888 10747109376 1380270944 14.419 98 43 84 0 1408995472 18151744 10572784
20:50:36 16006 8192000000 10335402352 10761121792 1409252976 14.677 100 56 112 2 1443853824 20643136 13957712
20:51:59 3037 8192000000 10355447488 10771849216 1411933456 14.701 100 65 130 2 1453174912 25224256 16017200
20:53:12 5918 8192000000 10332309168 10750541824 1403065776 14.622 100 48 96 0 1442984016 27180064 12738176
20:54:22 4443 8192000000 10356403728 10772287488 1418294880 14.758 100 61 122 1 1463733584 29709024 15729680
20:55:37 2207 8192000000 10364247488 10768728064 1406594368 14.654 100 78 157 1 1464277312 36825360 20857584
20:56:52 3774 8192000000 10365569920 10774687744 1406668624 14.654 100 81 162 5 1471459824 43335008 21456192
20:58:01 5041 8192000000 10351206832 10765819904 1398691520 14.583 100 53 106 0 1456937456 43800000 14445936
20:59:12 2923 8192000000 10387763504 10791092224 1404241328 14.633 100 91 180 1 1484130320 55999584 23889408
21:00:18 18336 8192000000 10337465248 10767167488 1390406768 14.509 100 36 72 2 1444538448 43982624 10149056
21:01:40 20963 8192000000 10344480208 10780381184 1403759376 14.628 100 31 62 0 1452005216 39614656 8631184
21:03:25 18218 8192000000 10322753488 10767646720 1382755856 14.441 100 19 38 0 1432918160 43934400 6227904
21:04:45 45845 8192000000 10303124384 10755084288 1373032336 14.354 99 12 24 0 1409256736 32853248 3371152
21:06:41 39925 8192000000 10273159600 10708787200 1314659568 13.828 95 19 38 2 1330265872 11055232 4551072
21:08:22 58595 8192000000 10288766704 10719150080 1356109376 14.202 98 32 61 2 1371016464 7356912 7550176
21:10:24 135491 8192000000 10290202208 10730323968 1357745280 14.217 99 33 66 0 1376174400 10676800 7752320
21:14:09 42044 8192000000 10294272944 10755207168 1380728512 14.423 100 36 70 1 1406082832 16877264 8477056
21:15:59 3103 8192000000 10296429248 10735091712 1366589536 14.296 99 48 96 1 1387407904 9848016 10970352
21:17:04 33227 8192000000 10292233984 10724257792 1373083840 14.355 99 26 50 2 1387174624 7979056 6111728
21:18:41 48768 8192000000 10279532528 10742345728 1355164112 14.194 98 13 26 0 1371718560 13354672 3199776
21:20:33 88716 8192000000 10279430112 10734333952 1353292160 14.177 97 7 14 1 1362821856 7803472 1726224
21:23:08 54408 8192000000 10301293520 10764226560 1393313376 14.535 100 13 26 0 1412994864 16228960 3452528
21:25:03 39026 8192000000 10305716400 10769997824 1396688192 14.565 100 27 54 1 1420758912 17492544 6578176
21:26:54 45461 8192000000 10303873216 10764505088 1400163872 14.596 100 20 40 0 1414605136 9770960 4670304
21:28:43 62249 8192000000 10306303376 10771296256 1397702976 14.575 100 11 22 1 1412243408 12194192 2346240
21:30:50 49285 8192000000 10306704560 10763264000 1389453888 14.501 99 8 16 1 1398564336 7512240 1598208
21:32:40 28176 8192000000 10316155776 10766708736 1388648576 14.494 99 35 70 0 1406611888 10326624 7636688
21:34:09 26230 8192000000 10301903920 10746114048 1352750080 14.172 97 23 46 0 1364881904 7152336 4979488
21:35:35 32135 8192000000 10296747408 10732281856 1332177776 13.987 97 42 84 1 1353479392 12436928 8864688
21:37:08 87206 8192000000 10290559936 10736398336 1355946272 14.201 97 12 24 0 1375261984 16542080 2773632
21:39:35 88590 8192000000 10297262016 10743615488 1347134944 14.122 97 27 54 1 1367666112 13503040 7028128
21:42:20 47941 8192000000 10276651008 10736676864 1365919104 14.290 99 10 20 0 1381533424 13568448 2045872
21:44:08 75047 8192000000 10272344912 10733580288 1350372608 14.151 98 10 20 0 1364848528 12298384 2177536
21:46:24 64042 8192000000 10283026608 10735366144 1356535872 14.206 98 19 38 0 1367531104 6992384 4002848
21:48:29 36509 8192000000 10275618112 10700664832 1339613760 14.054 98 50 99 1 1362380448 11588912 11177776
21:50:11 63279 8192000000 10281190528 10728448000 1341546976 14.071 98 13 26 1 1354099600 9515872 3036752
21:52:17 116867 8192000000 10288042064 10737197056 1346415536 14.115 98 13 26 0 1363600512 14585920 2599056
21:55:15 111879 8192000000 10304457872 10768093184 1367675104 14.306 99 4 8 0 1387560480 18988544 896832
21:58:08 87493 8192000000 10279965392 10737987584 1354705616 14.190 98 10 20 1 1367393952 10300320 2388016
22:00:42 60955 8192000000 10301065952 10738061312 1344531392 14.098 97 27 54 0 1363406928 13065584 5809952
22:02:50 8103 8192000000 10272282592 10729148416 1367201280 14.302 99 10 20 0 1382137072 12665360 2270432
22:03:59 37578 8192000000 10270003808 10717589504 1334355536 14.006 97 17 34 0 1344883792 7166464 3361792
22:05:39 77245 8192000000 10272642432 10735046656 1369536688 14.323 99 14 28 0 1380755200 7573168 3645344
22:07:56 50658 8192000000 10272058928 10732089344 1375556608 14.377 100 12 23 1 1383622512 5497328 2568576
22:10:27 60338 8192000000 10279468928 10741411840 1336384448 14.025 96 9 18 1 1345653488 7364928 1904112
22:12:50 55402 8192000000 10304037088 10757812224 1383860064 14.451 100 19 38 1 1396671856 8465904 4345888
22:14:48 29843 8192000000 10317210832 10768662528 1388369616 14.491 99 16 32 0 1404747440 12652464 3725360
22:16:18 46057 8192000000 10312264880 10756370432 1361905040 14.254 97 32 64 0 1387029360 17877024 7247296
22:18:04 40208 8192000000 10289910944 10746671104 1381497376 14.430 100 27 54 0 1403472112 15446528 6528208
22:19:45 87362 8192000000 10301826912 10753388544 1327684128 13.946 95 17 32 0 1349262560 17956992 3621440
22:22:15 23769 8192000000 10304683952 10744487936 1372566448 14.350 99 32 63 2 1387370048 7376400 7427200
22:23:43 15541 8192000000 10314878416 10746314752 1363480192 14.269 98 51 102 0 1390718448 16241984 10996272
22:25:04 114428 8192000000 10303047504 10754646016 1388167856 14.490 100 12 24 0 1411298032 20548144 2582032
22:28:19 53464 8192000000 10306969296 10782978048 1394934432 14.550 100 19 38 0 1420207504 21238272 4034800
22:30:19 67159 8192000000 10273964448 10735161344 1347120704 14.122 97 14 28 0 1355933040 5480896 3331440
22:32:30 95202 8192000000 10281173856 10740154368 1339247584 14.051 96 20 41 0 1352615760 8482384 4885792
22:35:09 75329 8192000000 10255425904 10718023680 1312773248 13.811 96 10 20 0 1326106976 10419456 2914272
22:37:24 27947 8192000000 9155374240 9402327040 310181584 3.648 23 7 14 0 314488496 2950064 1356848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-11-2021 22:39:26 to 11-11-2021 22:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-11-2021 22:39:42 to 11-11-2021 22:39:44.

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          241227
MON$OLDEST_ACTIVE               241228
MON$OLDEST_SNAPSHOT             241228
MON$NEXT_TRANSACTION            241228
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                       2031584
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:128: 
:129: mscs-test-161.budzdorov.ru    Thu Nov 11 22:37:54 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Thu Nov 11 22:37:55 2021
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
--- end of diff output ---

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

11.11.21 22:39:45. 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
2021-11-11 22:39:47 No severe PSQL-related problems occured
11.11.21 22:39:48. End of report.