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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-12 19:38:16
DTS_END                         2022-06-12 19:38:16.8480
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/51008
TRN_ID                          239023
ATT_ID                          625
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: 9442.11 20325 1133053
customer order: creation 172.53 347 20703
customer order: refuse 14.76 342 1771
order to supplier: creation 45.71 1292 5485
order to supplier: removal 3.52 106992 422
invoice (draft): creation 44.99 3440 5399
invoice (draft): removal 55.73 764 6688
invoice accept: apply 41.53 17581 4983
invoice accept: cancel 51.00 795 6120
customer reserve: creation 3777.71 176 453325
customer reserve: removal 4935.03 59 592204
realization accept: apply 81.78 188 9814
realization accept: cancel 87.41 110 10489
payment from customer: creation 59.01 123 7081
payment from customer: removal 5.82 79 698
payment to supplier: creation 49.07 145 5888
payment to supplier: removal 3.59 81 431
service: total inventory turnovers 4.69 1412 563
service: total monetary turnovers 7.38 34 886
service: aggregate perf. data 0.83 19048 99
service: refresh index statistics 0.03 253492 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-06-2022 19:40:58 to 12-06-2022 19:41:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5523
TEST_TIME 2 4909
TEST_TIME 3 4756
TEST_TIME 4 5052
TEST_TIME 5 4933
TEST_TIME 6 5707
TEST_TIME 7 5758
TEST_TIME 8 5849
TEST_TIME 9 6410
TEST_TIME 10 6395
TEST_TIME 11 6309
TEST_TIME 12 6348
TEST_TIME 13 6575
TEST_TIME 14 6481
TEST_TIME 15 6522
TEST_TIME 16 6664
TEST_TIME 17 7192
TEST_TIME 18 7295
TEST_TIME 19 7284
TEST_TIME 20 7403
TEST_TIME 21 7511
TEST_TIME 22 7763
TEST_TIME 23 8383
TEST_TIME 24 8536
TEST_TIME 25 8497
TEST_TIME 26 8476
TEST_TIME 27 9038
TEST_TIME 28 10064
TEST_TIME 29 10752
TEST_TIME 30 10656
TEST_TIME 31 10551
TEST_TIME 32 10387
TEST_TIME 33 10268
TEST_TIME 34 10542
TEST_TIME 35 10575
TEST_TIME 36 10460
TEST_TIME 37 10480
TEST_TIME 38 10284
TEST_TIME 39 10190
TEST_TIME 40 10031
TEST_TIME 41 10270
TEST_TIME 42 10115
TEST_TIME 43 10076
TEST_TIME 44 9951
TEST_TIME 45 9944
TEST_TIME 46 10179
TEST_TIME 47 10102
TEST_TIME 48 10038
TEST_TIME 49 9911
TEST_TIME 50 9818
TEST_TIME 51 9672
TEST_TIME 52 10009
TEST_TIME 53 9918
TEST_TIME 54 9929
TEST_TIME 55 9938
TEST_TIME 56 9885
TEST_TIME 57 9987
TEST_TIME 58 9940
TEST_TIME 59 9949
TEST_TIME 60 10000
TEST_TIME 61 9962
TEST_TIME 62 10040
TEST_TIME 63 10053
TEST_TIME 64 10170
TEST_TIME 65 10084
TEST_TIME 66 9958
TEST_TIME 67 9919
TEST_TIME 68 9826
TEST_TIME 69 9837
TEST_TIME 70 9784
TEST_TIME 71 9741
TEST_TIME 72 9720
TEST_TIME 73 9723
TEST_TIME 74 9623
TEST_TIME 75 9584
TEST_TIME 76 9622
TEST_TIME 77 9733
TEST_TIME 78 9707
TEST_TIME 79 9696
TEST_TIME 80 9758
TEST_TIME 81 9719
TEST_TIME 82 9748
TEST_TIME 83 9766
TEST_TIME 84 9804
TEST_TIME 85 9771
TEST_TIME 86 9854
TEST_TIME 87 9849
TEST_TIME 88 9848
TEST_TIME 89 9890
TEST_TIME 90 9855
TEST_TIME 91 9780
TEST_TIME 92 9792
TEST_TIME 93 9743
TEST_TIME 94 9706
TEST_TIME 95 9707
TEST_TIME 96 9667
TEST_TIME 97 9622
TEST_TIME 98 9570
TEST_TIME 99 9550
TEST_TIME 100 9626
TEST_TIME 101 9594
TEST_TIME 102 9601
TEST_TIME 103 9643
TEST_TIME 104 9628
TEST_TIME 105 9630
TEST_TIME 106 9647
TEST_TIME 107 9647
TEST_TIME 108 9681
TEST_TIME 109 9694
TEST_TIME 110 9661
TEST_TIME 111 9754
TEST_TIME 112 9781
TEST_TIME 113 9730
TEST_TIME 114 9663
TEST_TIME 115 9653
TEST_TIME 116 9617
TEST_TIME 117 9566
TEST_TIME 118 9537
TEST_TIME 119 9524
TEST_TIME 120 9450

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-06-2022 19:41:01 to 12-06-2022 19:41:05.

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 1554344 1554344 0 0.00 0 19692 6 0 0
doc_list_biud 1554338 1554338 0 0.00 0 35032 28 0 0
sp_add_invoice_to_stock 5095 4983 112 2.19 24 178344 17581 0 112
sp_cancel_adding_invoice 6120 6120 0 0.00 3 70941 796 0 0
sp_cancel_client_order 1771 1771 0 0.00 4 10698 342 0 0
sp_cancel_customer_reserve 592204 592204 0 0.00 1 23570 59 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 3656 80 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 5398 82 0 0
sp_cancel_supplier_invoice 6701 6688 13 0.19 2 26933 765 0 13
sp_cancel_supplier_order 422 422 0 0.00 15 1085368 106992 0 0
sp_cancel_write_off 10489 10489 0 0.00 1 11486 111 0 0
sp_client_order 20703 20703 0 0.00 3 27255 347 0 0
sp_customer_reserve 453325 453325 0 0.00 0 50603 176 0 0
sp_fill_shopping_cart 31584 31584 0 0.00 1 26879 40 0 0
sp_fill_shopping_cart_clo_res 453312 453312 0 0.00 0 15431 12 0 0
sp_get_clo_for_invoice 4970 4970 0 0.00 0 26665 289 0 0
sp_kill_cost_storno 17738 17738 0 0.00 0 7792 57 0 0
sp_kill_qstorno_ret_qs2qd 1202007 1202007 0 0.00 0 25371 32 0 0
sp_kill_qty_storno 632478 632478 0 0.00 0 35031 69 0 0
sp_lock_selected_doc 20662 20662 0 0.00 0 9194 4 0 0
sp_make_cost_storno 27753 27753 0 0.00 0 15543 59 0 0
sp_multiply_rows_for_pdistr 27753 27753 0 0.00 0 14533 33 0 0
sp_multiply_rows_for_qdistr 20703 20703 0 0.00 0 11903 135 0 0
sp_pay_from_customer 7081 7081 0 0.00 4 14552 124 0 0
sp_pay_to_supplier 5888 5888 0 0.00 5 10191 146 0 0
sp_payment_common 12969 12969 0 0.00 2 7797 32 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 0 10687 321 0 0
sp_qd_handle_on_invoice_upd_sts 11090 11090 0 0.00 0 35027 344 0 0
sp_qd_handle_on_reserve_upd_sts 9814 9814 0 0.00 0 19835 67 0 0
sp_reserve_write_off 9814 9814 0 0.00 6 19859 189 0 0
sp_supplier_invoice 5399 5399 0 0.00 6 81674 3441 0 0
sp_supplier_order 5485 5485 0 0.00 5 35379 1293 0 0
srv_aggregate_perf_data 99 99 0 0.00 3274 152463 19049 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1273 121487 47671 0 0
srv_find_qd_qs_mism 1086366 1086366 0 0.00 0 19691 5 0 0
srv_make_invnt_saldo 563 563 0 0.00 50 130625 1413 0 0
srv_make_money_saldo 886 886 0 0.00 0 9840 35 0 0
srv_recalc_idx_stat 4 4 0 0.00 159363 333985 253493 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 22223 174 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 8864 110 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 10478 27 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 4459 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 2260 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 22229 64 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1859 14 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 29105 1515 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 9161 19 0 0
t$perf-norm:sp_client_order 20703 20703 0 0.00 0 22506 23 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 6778 19 0 0
t$perf-norm:sp_pay_from_customer 7081 7081 0 0.00 0 5755 10 0 0
t$perf-norm:sp_pay_to_supplier 5888 5888 0 0.00 0 19738 18 0 0
t$perf-norm:sp_reserve_write_off 9814 9814 0 0.00 0 10400 13 0 0
t$perf-norm:sp_supplier_invoice 5399 5399 0 0.00 0 11728 19 0 0
t$perf-norm:sp_supplier_order 5485 5485 0 0.00 0 15948 21 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 99 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 130 2 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 881 24 0 0
t$perf-norm:srv_make_money_saldo 886 886 0 0.00 0 5203 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10162 9938 224 2.20 0 16797 18 0 224
v_all_customers 37566 37566 0 0.00 0 21 1 0 0
v_all_suppliers 30328 30328 0 0.00 0 22 1 0 0
v_all_wares 41406 41406 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 5059 16 0 0
v_cancel_client_order 3542 3542 0 0.00 0 1913 5 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 2888 9 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 4411 14 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 1645 5 0 0
v_cancel_supplier_invoice 826 800 26 3.14 0 3202 15 0 26
v_cancel_supplier_order 844 844 0 0.00 0 1173 5 0 0
v_cancel_write_off 2042 2042 0 0.00 0 2755 13 0 0
v_max_id_clo_ord 5485 5485 0 0.00 0 364 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 4530 14 0 0
v_max_id_ord_sup 5396 5396 0 0.00 0 1584 2 0 0
v_max_non_paid_invoice 1605 1605 0 0.00 0 4425 6 0 0
v_max_non_paid_realizn 3731 3731 0 0.00 0 374 3 0 0
v_min_id_clo_ord 5485 5485 0 0.00 0 6532 11 0 0
v_min_id_clo_res 983 983 0 0.00 0 14027 24 0 0
v_min_id_ord_sup 5396 5396 0 0.00 0 8259 12 0 0
v_min_non_paid_invoice 5888 5888 0 0.00 0 7791 33 0 0
v_min_non_paid_realizn 7081 7081 0 0.00 0 6001 20 0 0
v_our_firm 10540 10540 0 0.00 0 19 1 0 0
v_reserve_write_off 19628 19628 0 0.00 0 5193 7 0 0
x_lock_depdocs_on_canc_invoice 12408 12408 0 0.00 0 14223 126 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 9661 358 0 0
x_make_qstorno_1000_1200 5485 5485 0 0.00 1 35281 1205 0 0
x_make_qstorno_1200_2000 5399 5399 0 0.00 2 80614 3297 0 0
x_make_qstorno_2100_3300 453325 453325 0 0.00 0 50584 162 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 12-06-2022 19:41:05 to 12-06-2022 19:41:10.

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
17:08:20 8 8192000000 8805954128 8819675136 11260592 0.137 3 2 3 0 11407712 72176 74944
17:09:21 29172 8192000000 9896666176 10207793152 979879568 10.683 100 88 176 0 1009482832 6243584 23359680
17:10:55 14163 8192000000 9943579264 10265927680 1025428768 11.124 100 85 170 0 1055808896 8102272 22277856
17:12:12 15915 8192000000 9981195312 10312577024 1062325552 11.479 100 87 174 1 1094461360 9699296 22436512
17:13:40 12087 8192000000 10032503728 10377744384 1119829952 12.025 100 77 153 0 1149388640 11235280 18323408
17:14:53 8017 8192000000 10081584976 10437607424 1165972080 12.459 100 84 168 1 1198960304 13460224 19528000
17:16:04 6459 8192000000 10123659424 10490253312 1212077808 12.888 100 72 141 3 1239590624 11913648 15599168
17:17:13 10607 8192000000 10153786176 10531852288 1244532736 13.188 100 66 132 0 1270783936 12233168 14018032
17:18:27 2281 8192000000 10190788048 10571894784 1279281440 13.506 100 90 176 1 1311984912 13051376 19652096
17:19:31 2677 8192000000 10211719488 10593992704 1289385168 13.599 100 98 196 3 1324947072 14085952 21475952
17:20:35 2395 8192000000 10207679952 10604072960 1299438048 13.690 100 71 142 3 1325933232 9897792 16597392
17:21:41 4113 8192000000 10211562048 10617856000 1312505680 13.809 100 59 118 0 1336913744 10692944 13715120
17:22:47 5729 8192000000 10202819088 10614886400 1313396720 13.817 100 24 48 1 1324959888 6081472 5481696
17:23:55 7346 8192000000 10202003120 10621120512 1318725680 13.865 100 20 40 0 1333050944 9814528 4510736
17:25:02 33946 8192000000 10209896912 10635845632 1322260736 13.897 100 29 58 0 1334860592 5690928 6908928
17:26:38 3522 8192000000 10230020448 10646646784 1331752288 13.983 100 31 62 1 1346406000 8008480 6645232
17:27:43 6078 8192000000 10224389952 10643275776 1330556976 13.972 100 23 46 2 1341164496 5246464 5361056
17:28:51 45503 8192000000 10174537536 10588598272 1218325856 12.946 93 20 39 0 1228225888 5036240 4863792
17:30:46 6579 8192000000 10183794224 10587635712 1254417696 13.279 97 42 81 2 1271839744 7961584 9460464
17:31:53 11865 8192000000 10132807344 10535346176 1204708048 12.820 96 26 51 0 1216062560 5195104 6159408
17:33:05 6700 8192000000 10147405712 10541309952 1232021424 13.073 98 31 62 1 1244397328 5291360 7084544
17:34:14 52269 8192000000 10166648160 10572312576 1263256736 13.360 99 31 62 1 1276400304 5977024 7166544
17:36:08 5025 8192000000 10170960160 10582028288 1283819184 13.548 100 19 38 0 1293586672 5296304 4471184
17:37:15 6804 8192000000 10183027888 10595684352 1291189488 13.615 100 37 74 0 1305896592 5399440 9307664
17:38:25 11796 8192000000 10229415264 10632732672 1318828288 13.866 100 38 72 2 1332926416 5335072 8763056
17:39:43 20253 8192000000 10237675888 10653413376 1340280352 14.060 100 34 68 1 1356677744 8667632 7729760
17:41:11 56008 8192000000 10257336176 10681344000 1352437392 14.169 100 46 92 1 1374058608 10750384 10870832
17:43:11 47291 8192000000 10267018592 10697965568 1366339344 14.294 100 32 64 2 1387573264 13708224 7525696
17:45:07 5655 8192000000 10299853584 10719617024 1379746064 14.414 100 59 118 1 1411008912 17031680 14231168
17:46:18 15879 8192000000 10305384336 10729349120 1389721952 14.503 100 54 108 1 1422009408 19873968 12413488
17:47:40 10247 8192000000 10312353600 10738061312 1395088608 14.551 100 57 112 0 1425049216 16410592 13550016
17:49:00 14720 8192000000 10330602048 10751459328 1403257104 14.624 100 57 114 3 1436415392 19849872 13308416
17:50:22 6858 8192000000 10325159200 10751279104 1396396384 14.563 99 50 100 0 1435402208 25781408 13224416
17:51:36 4661 8192000000 10341090752 10764832768 1406713888 14.655 100 57 114 1 1448001616 26934656 14353072
17:52:47 2429 8192000000 10352908880 10768109568 1411144544 14.694 100 79 153 0 1459436256 30341216 17950496
17:53:58 1271 8192000000 10349851856 10760564736 1389838448 14.504 99 90 179 3 1448336624 36134096 22364080
17:55:02 2945 8192000000 10355854048 10765434880 1396659840 14.565 100 78 156 0 1459795040 43893296 19241904
17:56:14 3049 8192000000 10345352496 10746322944 1382580096 14.440 100 80 161 0 1445167904 42278672 20309136
17:57:23 4060 8192000000 10333276288 10739261440 1372175664 14.347 100 73 141 1 1436022400 44242848 19603888
17:58:34 4515 8192000000 10364121680 10774474752 1394282272 14.544 100 72 143 4 1469762720 55949296 19531152
17:59:43 1522 8192000000 10350159440 10751266816 1368610240 14.315 98 80 160 1 1447680752 58340656 20729856
18:00:50 2404 8192000000 10371577328 10778386432 1393385808 14.536 100 85 168 2 1474804800 59316496 22102496
18:01:57 21384 8192000000 10349779008 10779643904 1392697504 14.530 100 42 84 2 1457992992 53478240 11817248
18:03:22 34055 8192000000 10342822032 10776592384 1381842640 14.433 99 39 79 0 1448287760 55428512 11016608
18:05:26 39562 8192000000 10315443152 10778148864 1368467728 14.313 100 21 42 0 1435748400 60593904 6686768
18:07:07 37067 8192000000 10276613664 10733744128 1350508544 14.152 99 16 32 0 1385296912 31165536 3622832
18:08:45 104311 8192000000 10294702144 10733047808 1336511184 14.026 97 15 27 2 1354244704 13953520 3780000
18:11:33 121486 8192000000 10287283360 10750050304 1349123072 14.140 98 13 26 0 1369242368 17774464 2344832
18:14:36 63690 8192000000 10301779040 10745372672 1347170640 14.122 97 19 36 2 1367233024 15953760 4108624
18:17:03 98187 8192000000 10302674480 10753974272 1382880880 14.442 100 14 28 0 1407853200 21588768 3383552
18:19:57 37066 8192000000 10302792128 10748133376 1376345136 14.384 99 42 86 3 1394967808 8898368 9724304
18:21:36 57830 8192000000 10301713920 10752581632 1390978320 14.515 99 23 46 0 1407075536 10773488 5323728
18:23:41 46351 8192000000 10324505840 10779213824 1402619072 14.618 100 31 61 1 1426680096 15371744 8689280
18:26:07 29043 8192000000 10307786512 10767618048 1396086000 14.560 100 9 18 0 1414677264 16373360 2217904
18:27:39 52542 8192000000 10323498352 10781724672 1397644624 14.574 99 16 32 1 1417266160 15842864 3778672
18:29:47 41918 8192000000 10310724976 10781466624 1386807648 14.477 99 14 28 0 1410623216 20799664 3015904
18:31:31 48900 8192000000 10293508592 10744848384 1371123776 14.337 98 18 36 2 1384447392 9418624 3904992
18:33:21 25495 8192000000 10260237728 10705842176 1329491776 13.963 96 19 38 1 1341271040 7380688 4398576
18:34:53 90408 8192000000 10281342032 10737807360 1333150464 13.996 96 19 37 1 1353472880 16143040 4179376
18:37:25 117221 8192000000 10270802112 10713878528 1266702416 13.391 91 10 20 0 1280397712 11942656 1752640
18:40:35 84339 8192000000 10294652992 10758459392 1339217984 14.050 97 7 14 0 1362888736 22603504 1067248
18:43:00 98533 8192000000 10298746768 10737369088 1358749504 14.226 98 22 44 0 1372907760 9493360 4664896
18:45:43 58320 8192000000 10301248144 10746642432 1363316624 14.267 98 30 61 1 1379120128 9743328 6060176
18:48:59 91520 8192000000 10272045424 10733568000 1330981520 13.976 96 11 22 0 1346892816 13272128 2639168
18:51:30 96351 8192000000 10285146464 10739408896 1365146320 14.284 99 19 38 1 1385186912 15195280 4845312
18:54:15 50676 8192000000 10281558992 10742595584 1364473984 14.278 99 17 34 0 1382863632 14171312 4218336
18:56:07 72527 8192000000 10300423616 10747531264 1327421872 13.944 96 18 35 1 1341899424 10818704 3658848
18:58:21 67808 8192000000 10290788528 10743529472 1331869984 13.984 96 14 28 1 1350637168 15453472 3313712
19:00:35 52468 8192000000 10282515344 10731474944 1327554512 13.945 97 15 30 0 1345765616 14398720 3812384
19:02:36 56775 8192000000 10300607296 10749808640 1377368288 14.393 100 22 44 0 1391403984 9404784 4630912
19:04:36 69366 8192000000 10301488800 10765041664 1355658576 14.198 97 11 22 2 1374120368 16268816 2192976
19:06:48 84464 8192000000 10290057744 10742611968 1318968880 13.867 95 14 28 0 1331243648 9779472 2495296
19:09:12 53029 8192000000 10281116960 10731655168 1360069632 14.238 98 23 45 2 1372546528 5761360 6715536
19:11:06 28137 8192000000 10298387152 10769801216 1394325968 14.544 100 13 26 1 1407528880 10243936 2958976
19:12:35 60725 8192000000 10298117088 10764083200 1396798880 14.566 100 20 39 0 1411727840 10628240 4300720
19:14:36 91895 8192000000 10310605312 10782867456 1395188704 14.552 99 15 30 1 1415023648 16249104 3585840
19:17:08 57481 8192000000 10316303552 10786758656 1392994560 14.533 99 11 22 1 1418249760 22707200 2548000
19:19:07 45787 8192000000 10319339152 10785419264 1415787856 14.735 100 29 58 0 1433173104 10410320 6974928
19:21:01 74021 8192000000 10307821568 10759364608 1328289520 13.952 94 18 36 1 1339769440 7719472 3760448
19:23:23 92113 8192000000 10297033664 10755731456 1325063072 13.923 95 12 24 0 1337320016 9771504 2485440
19:26:28 27204 8192000000 10282633024 10731921408 1326252544 13.933 96 28 56 1 1343005024 10329824 6422656
19:28:02 22134 8192000000 10267897232 10712752128 1321673568 13.892 97 27 54 0 1335629872 7405424 6550880
19:29:32 72142 8192000000 10282370928 10759065600 1352837568 14.173 99 15 30 0 1379686224 23598048 3250608
19:31:44 95418 8192000000 10274883520 10733211648 1364626864 14.279 100 20 40 0 1382336560 13328752 4380944
19:34:20 39484 8192000000 10247190272 10698862592 1339736736 14.055 98 9 15 2 1348676528 6863424 2076368
19:36:00 35696 8192000000 10271440720 10726948864 1357688848 14.217 99 24 43 1 1370751072 6971024 6091200
19:37:58 42729 8192000000 9163713888 9420705792 325172640 3.817 24 8 12 2 330433056 3634864 1625552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-06-2022 19:41:11 to 12-06-2022 19:41:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-06-2022 19:41:27 to 12-06-2022 19:41:29.

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          237656
MON$OLDEST_ACTIVE               239391
MON$OLDEST_SNAPSHOT             239391
MON$NEXT_TRANSACTION            239391
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                       2044640
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:447: 
:448: mscs-test-161.budzdorov.ru    Sun Jun 12 19:38:57 2022
:449:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:450: 
--- end of diff output ---

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

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