Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2021 16: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 16646624 kB
MemAvailable 31447012 kB
Buffers 0 kB
Cached 14996912 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.9
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-12-09 16:37:55
DTS_END                         2021-12-09 16:37:55.6920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55470
TRN_ID                          232707
ATT_ID                          674
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: 9533.78 23913 1144054
customer order: creation 162.04 398 19445
customer order: refuse 13.35 357 1602
order to supplier: creation 44.23 1271 5308
order to supplier: removal 3.68 109758 441
invoice (draft): creation 43.63 3152 5236
invoice (draft): removal 56.63 774 6795
invoice accept: apply 39.87 17931 4784
invoice accept: cancel 51.70 804 6204
customer reserve: creation 3752.75 173 450330
customer reserve: removal 5069.03 63 608284
realization accept: apply 79.20 181 9504
realization accept: cancel 93.34 117 11201
payment from customer: creation 56.18 120 6741
payment from customer: removal 5.36 64 643
payment to supplier: creation 47.61 138 5713
payment to supplier: removal 3.30 88 396
service: total inventory turnovers 4.81 1213 577
service: total monetary turnovers 6.27 33 752
service: aggregate perf. data 0.78 21944 94
service: refresh index statistics 0.03 319687 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2021 16:39:00 to 09-12-2021 16:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1924
TEST_TIME 2 2475
TEST_TIME 3 3992
TEST_TIME 4 4344
TEST_TIME 5 4502
TEST_TIME 6 4780
TEST_TIME 7 4778
TEST_TIME 8 4734
TEST_TIME 9 4612
TEST_TIME 10 4741
TEST_TIME 11 4798
TEST_TIME 12 5037
TEST_TIME 13 5507
TEST_TIME 14 5624
TEST_TIME 15 5957
TEST_TIME 16 6380
TEST_TIME 17 6730
TEST_TIME 18 6918
TEST_TIME 19 7938
TEST_TIME 20 7984
TEST_TIME 21 8043
TEST_TIME 22 8452
TEST_TIME 23 8714
TEST_TIME 24 8909
TEST_TIME 25 8970
TEST_TIME 26 10288
TEST_TIME 27 11117
TEST_TIME 28 10838
TEST_TIME 29 10660
TEST_TIME 30 10687
TEST_TIME 31 10631
TEST_TIME 32 10504
TEST_TIME 33 10427
TEST_TIME 34 10211
TEST_TIME 35 10155
TEST_TIME 36 10005
TEST_TIME 37 9967
TEST_TIME 38 10436
TEST_TIME 39 10499
TEST_TIME 40 10339
TEST_TIME 41 10243
TEST_TIME 42 10261
TEST_TIME 43 10185
TEST_TIME 44 10199
TEST_TIME 45 10119
TEST_TIME 46 10068
TEST_TIME 47 10116
TEST_TIME 48 10051
TEST_TIME 49 10007
TEST_TIME 50 9934
TEST_TIME 51 10011
TEST_TIME 52 9987
TEST_TIME 53 10029
TEST_TIME 54 10048
TEST_TIME 55 10124
TEST_TIME 56 10140
TEST_TIME 57 10170
TEST_TIME 58 10020
TEST_TIME 59 9950
TEST_TIME 60 9957
TEST_TIME 61 9849
TEST_TIME 62 9819
TEST_TIME 63 9750
TEST_TIME 64 9730
TEST_TIME 65 9682
TEST_TIME 66 9698
TEST_TIME 67 9601
TEST_TIME 68 9658
TEST_TIME 69 9713
TEST_TIME 70 9669
TEST_TIME 71 9724
TEST_TIME 72 9778
TEST_TIME 73 9752
TEST_TIME 74 9848
TEST_TIME 75 9827
TEST_TIME 76 9866
TEST_TIME 77 9831
TEST_TIME 78 9987
TEST_TIME 79 10005
TEST_TIME 80 9970
TEST_TIME 81 9872
TEST_TIME 82 9822
TEST_TIME 83 9808
TEST_TIME 84 9777
TEST_TIME 85 9702
TEST_TIME 86 9682
TEST_TIME 87 9667
TEST_TIME 88 9643
TEST_TIME 89 9606
TEST_TIME 90 9561
TEST_TIME 91 9519
TEST_TIME 92 9492
TEST_TIME 93 9530
TEST_TIME 94 9572
TEST_TIME 95 9588
TEST_TIME 96 9597
TEST_TIME 97 9610
TEST_TIME 98 9615
TEST_TIME 99 9615
TEST_TIME 100 9672
TEST_TIME 101 9672
TEST_TIME 102 9684
TEST_TIME 103 9715
TEST_TIME 104 9727
TEST_TIME 105 9739
TEST_TIME 106 9778
TEST_TIME 107 9693
TEST_TIME 108 9645
TEST_TIME 109 9632
TEST_TIME 110 9661
TEST_TIME 111 9614
TEST_TIME 112 9569
TEST_TIME 113 9572
TEST_TIME 114 9605
TEST_TIME 115 9563
TEST_TIME 116 9564
TEST_TIME 117 9545
TEST_TIME 118 9541
TEST_TIME 119 9524
TEST_TIME 120 9539

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2021 16:39:04 to 09-12-2021 16:39:07.

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 1561674 1561674 0 0.00 0 15522 6 0 0
doc_list_biud 1561674 1561674 0 0.00 0 32123 30 0 0
sp_add_invoice_to_stock 4905 4784 121 2.46 24 197935 17931 0 121
sp_cancel_adding_invoice 6204 6204 0 0.00 4 85851 805 0 0
sp_cancel_client_order 1602 1602 0 0.00 4 11077 357 0 0
sp_cancel_customer_reserve 608284 608284 0 0.00 1 32124 63 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 3800 64 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 5942 89 0 0
sp_cancel_supplier_invoice 6804 6795 9 0.13 1 17220 775 0 9
sp_cancel_supplier_order 441 441 0 0.00 6 1183631 109759 0 0
sp_cancel_write_off 11201 11201 0 0.00 1 14990 118 0 0
sp_client_order 19445 19445 0 0.00 3 19179 398 0 0
sp_customer_reserve 450330 450330 0 0.00 0 148266 174 0 0
sp_fill_shopping_cart 29984 29984 0 0.00 1 20098 38 0 0
sp_fill_shopping_cart_clo_res 450326 450326 0 0.00 0 20376 13 0 0
sp_get_clo_for_invoice 4778 4778 0 0.00 0 24182 371 0 0
sp_kill_cost_storno 18444 18444 0 0.00 0 8819 58 0 0
sp_kill_qstorno_ret_qs2qd 1235005 1235005 0 0.00 0 32121 34 0 0
sp_kill_qty_storno 648809 648809 0 0.00 0 32122 73 0 0
sp_lock_selected_doc 19865 19865 0 0.00 0 6740 4 0 0
sp_make_cost_storno 26736 26736 0 0.00 0 15468 55 0 0
sp_multiply_rows_for_pdistr 26736 26736 0 0.00 0 8596 29 0 0
sp_multiply_rows_for_qdistr 19445 19445 0 0.00 0 17122 205 0 0
sp_pay_from_customer 6741 6741 0 0.00 4 15106 120 0 0
sp_pay_to_supplier 5713 5713 0 0.00 5 14788 138 0 0
sp_payment_common 12454 12454 0 0.00 1 14800 28 0 0
sp_qd_handle_on_cancel_clo 1602 1602 0 0.00 0 11051 327 0 0
sp_qd_handle_on_invoice_upd_sts 10982 10982 0 0.00 0 25960 332 0 0
sp_qd_handle_on_reserve_upd_sts 9504 9504 0 0.00 0 15956 65 0 0
sp_reserve_write_off 9504 9504 0 0.00 6 17816 181 0 0
sp_supplier_invoice 5236 5236 0 0.00 6 90589 3153 0 0
sp_supplier_order 5308 5308 0 0.00 5 93111 1271 0 0
srv_aggregate_perf_data 94 94 0 0.00 1973 134940 21945 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2190 171407 49446 0 0
srv_find_qd_qs_mism 1098498 1098498 0 0.00 0 9724 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 24 154675 1214 0 0
srv_make_money_saldo 752 752 0 0.00 1 2734 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 162433 619269 319688 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4784 4784 0 0.00 0 14624 211 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 1 7351 175 0 0
t$perf-norm:sp_cancel_client_order 1602 1602 0 0.00 0 7061 23 0 0
t$perf-norm:sp_cancel_customer_reserve 643 643 0 0.00 0 1097 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 3949 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 3661 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 2301 17 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 24515 1670 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 7005 20 0 0
t$perf-norm:sp_client_order 19445 19445 0 0.00 0 11139 22 0 0
t$perf-norm:sp_customer_reserve 909 909 0 0.00 0 3840 15 0 0
t$perf-norm:sp_pay_from_customer 6741 6741 0 0.00 0 9242 17 0 0
t$perf-norm:sp_pay_to_supplier 5713 5713 0 0.00 0 8168 19 0 0
t$perf-norm:sp_reserve_write_off 9504 9504 0 0.00 0 14370 16 0 0
t$perf-norm:sp_supplier_invoice 5236 5236 0 0.00 0 16409 29 0 0
t$perf-norm:sp_supplier_order 5308 5308 0 0.00 0 11838 23 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 1177 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3866 65 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 7904 45 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 1075 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9798 9556 242 2.46 0 12951 23 0 242
v_all_customers 36048 36048 0 0.00 0 16 1 0 0
v_all_suppliers 29692 29692 0 0.00 0 22 1 0 0
v_all_wares 38890 38890 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 1041 7 0 0
v_cancel_client_order 3204 3204 0 0.00 0 5416 8 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 803 4 0 0
v_cancel_customer_reserve 1286 1286 0 0.00 0 14048 15 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 5898 18 0 0
v_cancel_supplier_invoice 862 844 18 2.08 0 785 12 0 18
v_cancel_supplier_order 882 882 0 0.00 0 5846 13 0 0
v_cancel_write_off 1984 1984 0 0.00 0 2654 15 0 0
v_max_id_clo_ord 5308 5308 0 0.00 0 948 1 0 0
v_max_id_clo_res 909 909 0 0.00 0 4231 13 0 0
v_max_id_ord_sup 5231 5231 0 0.00 0 5665 6 0 0
v_max_non_paid_invoice 1406 1406 0 0.00 0 395 2 0 0
v_max_non_paid_realizn 3345 3345 0 0.00 0 746 3 0 0
v_min_id_clo_ord 5308 5308 0 0.00 0 1831 5 0 0
v_min_id_clo_res 909 909 0 0.00 0 2681 10 0 0
v_min_id_ord_sup 5231 5231 0 0.00 0 2935 3 0 0
v_min_non_paid_invoice 5713 5713 0 0.00 0 7466 17 0 0
v_min_non_paid_realizn 6741 6741 0 0.00 0 14793 27 0 0
v_our_firm 9634 9634 0 0.00 0 14 1 0 0
v_reserve_write_off 19006 19006 0 0.00 0 10152 8 0 0
x_lock_depdocs_on_canc_invoice 12577 12577 0 0.00 0 14269 122 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 11467 336 0 0
x_make_qstorno_1000_1200 5308 5308 0 0.00 1 93085 1192 0 0
x_make_qstorno_1200_2000 5236 5236 0 0.00 3 90267 3024 0 0
x_make_qstorno_2100_3300 450330 450330 0 0.00 0 148264 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2021 16:39:08 to 09-12-2021 16:39:12.

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
14:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:00 26411 8192000000 9895050736 10205106176 968370416 10.571 100 88 174 3 999012624 6393872 24248336
14:10:30 23577 8192000000 9947607040 10271162368 1029370096 11.162 100 87 174 0 1060370096 8579440 22420560
14:11:57 16445 8192000000 9979125408 10313310208 1065975104 11.514 100 87 174 1 1098348016 10248096 22124816
14:13:21 14294 8192000000 10029031744 10378764288 1115736320 11.987 100 76 152 1 1144904080 11582592 17585168
14:14:47 9694 8192000000 10085844016 10446901248 1171865040 12.514 100 82 164 0 1203717024 13210640 18641344
14:15:58 7533 8192000000 10127012736 10498449408 1215241040 12.918 100 83 166 2 1247492672 13765360 18486272
14:17:08 3369 8192000000 10162518544 10537316352 1249017632 13.229 100 90 180 1 1282034288 13198576 19818080
14:18:13 1546 8192000000 10190265488 10572742656 1283028096 13.541 100 90 180 0 1316922576 14348800 19545680
14:19:16 13530 8192000000 10209093040 10595991552 1299782304 13.693 100 74 146 2 1326160928 10014016 16364608
14:20:31 4024 8192000000 10204215616 10608414720 1306961920 13.758 100 37 71 0 1323388400 7345216 9081264
14:21:37 50895 8192000000 10214916080 10617724928 1309734768 13.784 100 29 59 0 1322413552 5170480 7508304
14:23:32 4431 8192000000 10209425760 10625888256 1315276080 13.834 100 25 50 1 1327918000 6864208 5777712
14:24:39 8013 8192000000 10215694640 10637524992 1327012496 13.940 100 19 38 2 1337147792 6218928 3916368
14:25:48 12174 8192000000 10221261776 10643623936 1318578144 13.864 99 16 32 0 1332059136 9380320 4100672
14:27:01 57476 8192000000 10210605856 10630299648 1277098016 13.487 96 30 60 1 1293940240 10081328 6760896
14:28:59 3173 8192000000 10179388784 10579660800 1269800864 13.420 99 39 78 1 1286104672 7513888 8789920
14:30:05 8758 8192000000 10129380528 10523033600 1165939296 12.459 92 18 37 0 1174784560 4745168 4100096
14:31:17 12011 8192000000 10155714112 10548236288 1235376336 13.104 98 32 64 1 1249469504 5441328 8651840
14:32:30 32106 8192000000 10147598368 10553483264 1237658400 13.125 98 39 78 1 1252612896 5492288 9462208
14:34:05 6021 8192000000 10174918272 10580439040 1280284656 13.516 100 41 82 1 1297209120 7218208 9706256
14:35:14 11040 8192000000 10194124752 10607505408 1297650560 13.674 100 18 36 1 1311088352 9338960 4098832
14:36:27 39717 8192000000 10193756144 10612178944 1305506080 13.745 100 23 46 1 1316967104 5679360 5781664
14:38:39 18188 8192000000 10238407936 10647666688 1331780720 13.983 100 44 88 1 1347947488 6601152 9565616
14:40:01 12349 8192000000 10261056528 10673713152 1351126752 14.158 100 56 107 2 1379098624 15740464 12231408
14:41:16 16649 8192000000 10285503488 10702839808 1367953216 14.309 100 62 121 1 1402644816 20440112 14251488
14:42:37 11665 8192000000 10308275088 10728046592 1381326048 14.428 100 60 118 0 1419613952 23932736 14355168
14:43:52 43473 8192000000 10315252528 10747838464 1400285664 14.598 100 43 83 2 1435387408 23791488 11310256
14:45:39 10167 8192000000 10335276576 10762207232 1413688496 14.717 100 39 78 1 1447907760 23549584 10669680
14:46:54 3810 8192000000 10367745696 10782486528 1426245744 14.828 100 73 144 1 1474471856 31718848 16507264
14:48:04 12180 8192000000 10371973920 10795745280 1436243600 14.916 100 63 124 3 1485204752 33065936 15895216
14:49:22 3492 8192000000 10387814752 10810241024 1443572240 14.981 100 59 119 1 1496053488 37571984 14909264
14:50:29 2839 8192000000 10405274112 10823995392 1448230480 15.022 100 81 162 1 1516978144 48198336 20549328
14:51:36 4339 8192000000 10408418496 10825740288 1444589408 14.990 100 77 154 0 1512666576 48716368 19360800
14:52:44 3347 8192000000 10389517440 10801135616 1401214368 14.606 98 87 174 1 1477269360 53191744 22863248
14:53:53 4565 8192000000 10377851152 10792796160 1406078384 14.649 100 71 141 1 1479264416 55718192 17467840
14:55:02 2188 8192000000 10382519648 10791030784 1400887824 14.603 100 88 176 2 1475620192 53738720 20993648
14:56:12 2725 8192000000 10393043936 10801586176 1407298064 14.660 100 80 159 0 1490697024 62812016 20586944
14:57:19 5482 8192000000 10342529152 10750545920 1375513552 14.376 99 69 139 1 1442503968 49141728 17848688
14:58:28 6603 8192000000 10363999376 10793463808 1396008784 14.559 99 46 92 1 1460219424 51694304 12516336
14:59:44 25054 8192000000 10364709008 10800132096 1393774688 14.540 99 42 84 1 1465304672 59654112 11875872
15:01:23 30570 8192000000 10340998032 10778370048 1388943232 14.496 100 29 58 1 1455088704 56945584 9199888
15:02:57 22801 8192000000 10323193904 10783014912 1365178016 14.284 100 22 45 0 1435430912 63247872 7005024
15:04:24 49310 8192000000 10277375200 10718420992 1333141328 13.996 97 10 19 0 1351493440 16264544 2087568
15:06:14 29263 8192000000 10269184192 10705096704 1327766928 13.947 97 36 72 1 1343063424 7903840 7392656
15:07:47 103397 8192000000 10269110240 10707058688 1288631712 13.592 94 15 30 1 1299169600 6834176 3703712
15:10:36 171406 8192000000 10301576752 10746429440 1326566768 13.936 95 18 35 2 1349654672 19117344 3970560
15:14:28 39883 8192000000 10289283600 10745434112 1373588864 14.359 100 15 30 1 1404236528 26608112 4039552
15:16:17 12433 8192000000 10266012048 10719707136 1359749136 14.235 99 10 21 0 1376517504 14791056 1977312
15:18:04 59920 8192000000 10287395216 10745688064 1385795024 14.468 100 11 22 0 1402493024 13853008 2844992
15:20:04 132555 8192000000 10293660432 10748506112 1385638560 14.467 100 14 28 1 1401078432 12436928 3002944
15:23:19 44725 8192000000 10305290800 10759974912 1404623872 14.636 100 32 64 1 1421125856 9470656 7031328
15:25:45 37130 8192000000 10306343248 10759294976 1393385072 14.536 99 16 32 1 1404727936 8213376 3129488
15:27:23 44153 8192000000 10307513984 10760339456 1405266496 14.642 100 22 44 1 1422084272 11962768 4855008
15:29:08 112230 8192000000 10305306112 10766323712 1346099632 14.112 95 16 31 0 1365658496 16161792 3397072
15:32:05 79216 8192000000 10296121648 10750427136 1365666976 14.288 98 18 36 0 1382690240 12923184 4100080
15:34:26 54102 8192000000 10301033984 10754568192 1376295504 14.383 99 45 90 2 1398205264 12367600 9542160
15:36:55 67399 8192000000 10274321072 10718928896 1327769520 13.947 95 14 29 0 1340261392 9144864 3347008
15:39:05 5216 8192000000 10293442928 10730393600 1370566752 14.332 99 52 104 1 1393156080 11642624 10946704
15:40:13 57009 8192000000 10291587952 10749132800 1362731344 14.262 98 21 42 2 1380841840 12632432 5478064
15:42:23 57899 8192000000 10269939504 10725785600 1358856576 14.227 99 9 18 0 1375678832 14636256 2186000
15:45:09 59837 8192000000 10286335072 10749267968 1369651648 14.324 99 15 30 1 1390484768 17452496 3380624
15:47:31 88742 8192000000 10285247136 10743783424 1346559920 14.117 98 11 22 0 1369373312 20176336 2637056
15:50:02 40814 8192000000 10261756224 10705604608 1331964512 13.985 98 41 82 0 1351104960 10395328 8745120
15:52:03 75100 8192000000 10262324624 10708365312 1335981824 14.021 98 12 24 1 1352212800 13734592 2496384
15:54:22 84904 8192000000 10270127456 10738044928 1351179616 14.158 100 31 62 0 1379920640 22192944 6548080
15:57:12 40664 8192000000 10271110608 10705588224 1332705952 13.992 98 24 48 0 1347949808 9682848 5561008
15:58:54 89685 8192000000 10268632624 10723352576 1350444544 14.151 99 26 52 1 1371602416 14960384 6197488
16:01:46 49075 8192000000 10245526624 10710523904 1345367744 14.106 99 8 16 0 1357846480 10662400 1816336
16:03:35 81950 8192000000 10244651664 10704871424 1328613200 13.955 98 21 42 0 1343203792 9619296 4971296
16:06:06 73975 8192000000 10266877376 10723942400 1362944944 14.264 100 19 37 2 1380036560 12115600 4976016
16:08:21 68956 8192000000 10278070624 10741137408 1380616800 14.422 100 13 26 0 1395772240 11939200 3216240
16:10:34 61322 8192000000 10275978800 10733563904 1355495680 14.197 98 17 34 0 1371291824 12024304 3771840
16:12:39 59493 8192000000 10278106352 10737172480 1351637792 14.162 98 8 16 0 1364211344 11011680 1561872
16:14:44 32521 8192000000 10291149728 10738114560 1368508224 14.314 99 29 58 3 1383515280 8449104 6557952
16:16:22 91933 8192000000 10302623712 10768588800 1388845520 14.496 100 13 26 3 1407693344 16047408 2800416
16:19:01 98731 8192000000 10305153696 10767818752 1385161296 14.463 99 27 54 0 1406469936 15490416 5818224
16:21:41 100437 8192000000 10299831712 10761277440 1344249824 14.096 96 13 26 1 1359613744 12538224 2825696
16:24:26 121056 8192000000 10289743952 10750885888 1371626896 14.342 98 19 38 0 1388850592 12410928 4812768
16:27:31 62242 8192000000 10264301696 10730262528 1312732192 13.811 95 12 24 0 1327178560 12132576 2313792
16:29:34 97982 8192000000 10283163248 10747150336 1373018320 14.354 99 15 30 0 1388453808 11854784 3580704
16:33:49 53493 8192000000 10289052528 10741669888 1373567760 14.359 99 10 20 0 1382310976 6360512 2382704
16:35:44 34903 8192000000 10284813520 10737344512 1371002176 14.336 99 19 38 1 1382937312 7487168 4447968
16:37:21 48517 8192000000 8897984256 9052364800 71001840 0.859 6 1 2 0 74261104 3216912 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-12-2021 16:39:14 to 09-12-2021 16:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2021 16:39:30 to 09-12-2021 16:39:32.

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          233634
MON$OLDEST_ACTIVE               233635
MON$OLDEST_SNAPSHOT             233635
MON$NEXT_TRANSACTION            233635
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                       2031648
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:150: 
:151: mscs-test-161.budzdorov.ru    Thu Dec  9 16:38:11 2021
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:153: 
--- end of diff output ---

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

09.12.21 16:39:33. 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-12-09 16:39:35 No severe PSQL-related problems occured
09.12.21 16:39:36. End of report.