Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.10.2021 01: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16058772 kB
MemAvailable 30846308 kB
Buffers 0 kB
Cached 15621728 kB
SwapCached 4900 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-10-27 01:37:50
DTS_END                         2021-10-27 01:37:50.9750
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/36322
TRN_ID                          243903
ATT_ID                          713
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: 9424.01 20467 1130881
customer order: creation 178.35 355 21402
customer order: refuse 14.60 328 1752
order to supplier: creation 46.99 1223 5639
order to supplier: removal 3.39 108780 407
invoice (draft): creation 46.53 3373 5583
invoice (draft): removal 52.37 795 6284
invoice accept: apply 43.49 18097 5219
invoice accept: cancel 47.64 788 5717
customer reserve: creation 4011.28 179 481354
customer reserve: removal 4667.38 62 560085
realization accept: apply 84.99 166 10199
realization accept: cancel 92.83 101 11140
payment from customer: creation 61.58 102 7390
payment from customer: removal 6.08 90 730
payment to supplier: creation 50.23 106 6028
payment to supplier: removal 3.69 75 443
service: total inventory turnovers 4.87 1206 584
service: total monetary turnovers 6.73 47 807
service: aggregate perf. data 0.95 17316 114
service: refresh index statistics 0.03 256147 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-10-2021 01:38:59 to 27-10-2021 01:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3518
TEST_TIME 2 3758
TEST_TIME 3 4792
TEST_TIME 4 5135
TEST_TIME 5 4821
TEST_TIME 6 4705
TEST_TIME 7 5263
TEST_TIME 8 5665
TEST_TIME 9 6353
TEST_TIME 10 6387
TEST_TIME 11 6702
TEST_TIME 12 6853
TEST_TIME 13 7106
TEST_TIME 14 7208
TEST_TIME 15 7225
TEST_TIME 16 7629
TEST_TIME 17 7747
TEST_TIME 18 7689
TEST_TIME 19 7998
TEST_TIME 20 8047
TEST_TIME 21 8592
TEST_TIME 22 9311
TEST_TIME 23 9189
TEST_TIME 24 9627
TEST_TIME 25 9561
TEST_TIME 26 10026
TEST_TIME 27 11289
TEST_TIME 28 11198
TEST_TIME 29 11235
TEST_TIME 30 10967
TEST_TIME 31 10812
TEST_TIME 32 10683
TEST_TIME 33 10740
TEST_TIME 34 10807
TEST_TIME 35 10741
TEST_TIME 36 10503
TEST_TIME 37 10432
TEST_TIME 38 10309
TEST_TIME 39 10172
TEST_TIME 40 10246
TEST_TIME 41 10422
TEST_TIME 42 10322
TEST_TIME 43 10187
TEST_TIME 44 10103
TEST_TIME 45 9970
TEST_TIME 46 9927
TEST_TIME 47 9980
TEST_TIME 48 9955
TEST_TIME 49 9910
TEST_TIME 50 9839
TEST_TIME 51 9780
TEST_TIME 52 9688
TEST_TIME 53 9651
TEST_TIME 54 9671
TEST_TIME 55 9743
TEST_TIME 56 9779
TEST_TIME 57 9862
TEST_TIME 58 9884
TEST_TIME 59 9905
TEST_TIME 60 9865
TEST_TIME 61 9952
TEST_TIME 62 9989
TEST_TIME 63 10008
TEST_TIME 64 10082
TEST_TIME 65 10152
TEST_TIME 66 10231
TEST_TIME 67 10106
TEST_TIME 68 9996
TEST_TIME 69 9923
TEST_TIME 70 9897
TEST_TIME 71 9855
TEST_TIME 72 9811
TEST_TIME 73 9774
TEST_TIME 74 9682
TEST_TIME 75 9697
TEST_TIME 76 9646
TEST_TIME 77 9674
TEST_TIME 78 9645
TEST_TIME 79 9713
TEST_TIME 80 9789
TEST_TIME 81 9815
TEST_TIME 82 9825
TEST_TIME 83 9814
TEST_TIME 84 9809
TEST_TIME 85 9853
TEST_TIME 86 9837
TEST_TIME 87 9798
TEST_TIME 88 9857
TEST_TIME 89 9760
TEST_TIME 90 9702
TEST_TIME 91 9661
TEST_TIME 92 9707
TEST_TIME 93 9655
TEST_TIME 94 9626
TEST_TIME 95 9629
TEST_TIME 96 9626
TEST_TIME 97 9651
TEST_TIME 98 9695
TEST_TIME 99 9679
TEST_TIME 100 9730
TEST_TIME 101 9724
TEST_TIME 102 9726
TEST_TIME 103 9742
TEST_TIME 104 9712
TEST_TIME 105 9710
TEST_TIME 106 9712
TEST_TIME 107 9717
TEST_TIME 108 9690
TEST_TIME 109 9740
TEST_TIME 110 9688
TEST_TIME 111 9622
TEST_TIME 112 9573
TEST_TIME 113 9589
TEST_TIME 114 9536
TEST_TIME 115 9507
TEST_TIME 116 9479
TEST_TIME 117 9452
TEST_TIME 118 9428
TEST_TIME 119 9400
TEST_TIME 120 9432

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-10-2021 01:39:02 to 27-10-2021 01:39:06.

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 1576355 1576355 0 0.00 0 15709 6 0 0
doc_list_biud 1576354 1576354 0 0.00 0 33450 28 0 0
sp_add_invoice_to_stock 5342 5219 123 2.30 19 178221 18097 0 123
sp_cancel_adding_invoice 5717 5717 0 0.00 5 65144 789 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 11424 329 0 0
sp_cancel_customer_reserve 560085 560085 0 0.00 1 20438 62 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 3 8269 91 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 5553 76 0 0
sp_cancel_supplier_invoice 6292 6284 8 0.12 2 33460 795 0 8
sp_cancel_supplier_order 407 407 0 0.00 6 1150777 108781 0 0
sp_cancel_write_off 11140 11140 0 0.00 1 9547 101 0 0
sp_client_order 21402 21402 0 0.00 3 19293 356 0 0
sp_customer_reserve 481354 481354 0 0.00 0 70724 179 0 0
sp_fill_shopping_cart 32621 32621 0 0.00 1 16220 37 0 0
sp_fill_shopping_cart_clo_res 481346 481346 0 0.00 0 14981 12 0 0
sp_get_clo_for_invoice 5208 5208 0 0.00 0 19228 314 0 0
sp_kill_cost_storno 18030 18030 0 0.00 0 15700 56 0 0
sp_kill_qstorno_ret_qs2qd 1138001 1138001 0 0.00 0 33444 33 0 0
sp_kill_qty_storno 600791 600791 0 0.00 0 33447 72 0 0
sp_lock_selected_doc 21353 21353 0 0.00 0 5549 4 0 0
sp_make_cost_storno 28825 28825 0 0.00 0 13993 48 0 0
sp_multiply_rows_for_pdistr 28824 28824 0 0.00 0 6571 26 0 0
sp_multiply_rows_for_qdistr 21402 21402 0 0.00 0 12602 169 0 0
sp_pay_from_customer 7390 7390 0 0.00 4 12843 103 0 0
sp_pay_to_supplier 6028 6028 0 0.00 5 12861 106 0 0
sp_payment_common 13418 13418 0 0.00 1 12808 23 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 11415 305 0 0
sp_qd_handle_on_invoice_upd_sts 10924 10924 0 0.00 0 18768 347 0 0
sp_qd_handle_on_reserve_upd_sts 10199 10199 0 0.00 0 16800 63 0 0
sp_reserve_write_off 10199 10199 0 0.00 6 16813 166 0 0
sp_supplier_invoice 5583 5583 0 0.00 6 82718 3374 0 0
sp_supplier_order 5639 5639 0 0.00 5 47903 1224 0 0
srv_aggregate_perf_data 114 114 0 0.00 2976 124249 17317 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1652 174002 49839 0 0
srv_find_qd_qs_mism 1081280 1081280 0 0.00 0 12959 5 0 0
srv_make_invnt_saldo 584 584 0 0.00 39 105023 1206 0 0
srv_make_money_saldo 807 807 0 0.00 1 15075 48 0 0
srv_recalc_idx_stat 4 4 0 0.00 148079 442255 256147 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5219 5219 0 0.00 0 16526 186 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 4353 102 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 10128 15 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 547 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 930 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 1561 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 3934 36 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 31070 1729 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 7828 23 0 0
t$perf-norm:sp_client_order 21402 21402 0 0.00 0 8274 17 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 4013 11 0 0
t$perf-norm:sp_pay_from_customer 7390 7390 0 0.00 0 5264 8 0 0
t$perf-norm:sp_pay_to_supplier 6028 6028 0 0.00 0 4404 10 0 0
t$perf-norm:sp_reserve_write_off 10200 10200 0 0.00 0 4480 7 0 0
t$perf-norm:sp_supplier_invoice 5583 5583 0 0.00 0 9889 16 0 0
t$perf-norm:sp_supplier_order 5639 5639 0 0.00 0 4764 14 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 743 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 55 1 0 0
t$perf-norm:srv_make_invnt_saldo 584 584 0 0.00 0 4130 34 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 3706 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10658 10412 246 2.30 0 5033 17 0 246
v_all_customers 39002 39002 0 0.00 0 174 1 0 0
v_all_suppliers 31284 31284 0 0.00 0 175 1 0 0
v_all_wares 42804 42804 0 0.00 0 115 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 1102 8 0 0
v_cancel_client_order 3504 3504 0 0.00 0 2935 4 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 8229 10 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 1084 4 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 2119 7 0 0
v_cancel_supplier_invoice 838 822 16 1.90 0 755 17 0 16
v_cancel_supplier_order 814 814 0 0.00 0 297 3 0 0
v_cancel_write_off 2084 2084 0 0.00 0 8248 23 0 0
v_max_id_clo_ord 5639 5639 0 0.00 0 2354 2 0 0
v_max_id_clo_res 986 986 0 0.00 0 2944 17 0 0
v_max_id_ord_sup 5580 5580 0 0.00 0 2215 3 0 0
v_max_non_paid_invoice 1605 1605 0 0.00 0 1137 3 0 0
v_max_non_paid_realizn 3958 3958 0 0.00 0 4044 5 0 0
v_min_id_clo_ord 5639 5639 0 0.00 0 3972 7 0 0
v_min_id_clo_res 986 986 0 0.00 0 6236 20 0 0
v_min_id_ord_sup 5580 5580 0 0.00 0 5548 6 0 0
v_min_non_paid_invoice 6028 6028 0 0.00 0 4768 11 0 0
v_min_non_paid_realizn 7390 7390 0 0.00 0 12792 21 0 0
v_our_firm 10666 10666 0 0.00 0 13 1 0 0
v_reserve_write_off 20398 20398 0 0.00 0 8124 6 0 0
x_lock_depdocs_on_canc_invoice 11590 11590 0 0.00 0 8756 120 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 22245 402 0 0
x_make_qstorno_1000_1200 5639 5639 0 0.00 2 47891 1139 0 0
x_make_qstorno_1200_2000 5583 5583 0 0.00 2 79310 3248 0 0
x_make_qstorno_2100_3300 481354 481354 0 0.00 0 70722 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-10-2021 01:39:07 to 27-10-2021 01:39:11.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:07:54 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:56 35690 8192000000 9909790864 10223943680 985992640 10.743 100 89 178 1 1015065472 6276176 22796656
23:10:36 23344 8192000000 9956260688 10280128512 1035955936 11.226 100 89 178 1 1067311136 9059216 22295984
23:12:04 12506 8192000000 9983065504 10318962688 1072151168 11.573 100 81 161 1 1101974576 9844832 19978576
23:13:17 14408 8192000000 10052446304 10401120256 1127470192 12.098 100 80 160 1 1158652592 12010880 19171520
23:14:36 8254 8192000000 10097556608 10460393472 1182184432 12.611 100 76 152 1 1212379280 13148192 17046656
23:15:45 3882 8192000000 10132213856 10499526656 1214874672 12.914 100 77 153 1 1244444656 12809392 16760592
23:16:53 3299 8192000000 10182369056 10564374528 1265317808 13.379 100 79 158 1 1296903840 12982976 18603056
23:17:59 1706 8192000000 10206154896 10589855744 1289719360 13.602 100 91 182 1 1324183184 13848496 20615328
23:19:02 2986 8192000000 10198036320 10597785600 1299823152 13.694 100 52 100 1 1320664208 10001568 10839488
23:20:05 7177 8192000000 10200750624 10612244480 1308314016 13.771 100 24 48 0 1319430496 6193552 4922928
23:21:16 6929 8192000000 10207145040 10619314176 1315899216 13.840 100 27 54 2 1328519040 6841264 5778560
23:22:24 72604 8192000000 10222147808 10634784768 1322784128 13.902 100 49 98 1 1340309552 5691984 11833440
23:24:37 5975 8192000000 10225757600 10646134784 1334524256 14.008 100 33 66 1 1348341632 6686880 7130496
23:25:46 6195 8192000000 10219870560 10640003072 1327780192 13.947 100 20 41 1 1337710560 5445392 4484976
23:26:53 16596 8192000000 10211636128 10624770048 1289068960 13.596 97 12 24 0 1297357552 5596736 2691856
23:28:10 18016 8192000000 10190282224 10608517120 1279390384 13.507 97 18 36 1 1289347808 5757632 4199792
23:29:32 8625 8192000000 10157132032 10550345728 1210785744 12.876 96 26 52 1 1222287312 5315536 6186032
23:30:43 6836 8192000000 10160824656 10553708544 1235716592 13.107 98 34 68 2 1249332560 5523616 8092352
23:31:51 36883 8192000000 10148268480 10542202880 1231973488 13.072 98 22 42 2 1241554080 4748592 4832000
23:33:37 7175 8192000000 10175320624 10586685440 1279850976 13.512 100 20 40 0 1294367152 10083920 4432256
23:34:50 14690 8192000000 10191234800 10604990464 1302355104 13.717 100 18 37 1 1312013984 5517168 4141712
23:36:07 8931 8192000000 10201113840 10617561088 1312960672 13.813 100 11 22 1 1321071776 5489984 2621120
23:37:20 12403 8192000000 10217040496 10634162176 1323979984 13.913 100 38 76 1 1339882928 7522448 8380496
23:38:37 51556 8192000000 10262946864 10677637120 1351832752 14.164 100 47 94 2 1375423104 13261520 10328832
23:40:30 7646 8192000000 10282216448 10695598080 1369269264 14.320 100 59 118 1 1397568752 13630096 14669392
23:41:42 24572 8192000000 10295268992 10716336128 1383286128 14.446 100 44 88 2 1404382368 11330352 9765888
23:43:28 14384 8192000000 10315521616 10739109888 1398605440 14.583 100 40 79 1 1427300144 18649520 10045184
23:44:54 1652 8192000000 10342585488 10760478720 1411875648 14.701 100 67 133 0 1451103216 22813328 16414240
23:46:00 2198 8192000000 10337299728 10759708672 1406382208 14.652 100 58 116 2 1447057984 26279552 14396224
23:47:08 17164 8192000000 10351487952 10772463616 1406294432 14.651 99 59 118 2 1446155776 25654720 14206624
23:48:30 5815 8192000000 10350946432 10772955136 1407917904 14.665 99 61 120 2 1448367456 26087040 14362512
23:49:39 3395 8192000000 10338791152 10755571712 1397271040 14.571 100 82 163 0 1447731920 30521024 19939856
23:50:49 5566 8192000000 10337294768 10750693376 1386718336 14.477 99 72 144 1 1435726496 31485568 17522592
23:51:58 3419 8192000000 10353451552 10762305536 1392448992 14.528 99 87 175 1 1451492144 37779168 21263984
23:53:11 5706 8192000000 10367023552 10780397568 1404959072 14.639 100 75 150 2 1468472208 45199664 18313472
23:54:26 2329 8192000000 10369224048 10779357184 1400986864 14.604 99 80 160 1 1470422224 49234624 20200736
23:55:32 3776 8192000000 10383535776 10792779776 1403884720 14.630 99 82 163 2 1483448752 58415520 21148512
23:56:42 3032 8192000000 10366827360 10774364160 1386955712 14.479 99 80 160 1 1467696096 60157248 20583136
23:57:51 3599 8192000000 10378763728 10785710080 1390404288 14.509 100 79 156 1 1478071152 66799648 20867216
23:58:59 17069 8192000000 10342809968 10788577280 1387482384 14.483 100 45 90 0 1459209728 59058112 12669232
00:00:28 22732 8192000000 10325361952 10752053248 1353005040 14.175 98 28 55 2 1414956752 54047664 7904048
00:01:53 36369 8192000000 10311348944 10747400192 1363086784 14.265 99 25 50 1 1417862176 47765152 7010240
00:03:31 30891 8192000000 10305098960 10745114624 1337775664 14.037 99 13 26 1 1395927376 53980960 4170752
00:05:03 56243 8192000000 10263589296 10703626240 1315919744 13.840 97 13 26 0 1340365888 20723776 3722368
00:07:01 96379 8192000000 10279386208 10711478272 1327791344 13.947 97 37 74 0 1346800624 11119696 7889584
00:09:39 174002 8192000000 10282358912 10730524672 1324842208 13.921 96 21 43 0 1339222128 10676512 3703408
00:13:35 52921 8192000000 10312465280 10758209536 1395617136 14.556 100 29 58 0 1411409808 9262432 6530240
00:15:37 18684 8192000000 10322674784 10768302080 1407298160 14.660 100 40 80 3 1429761296 12981088 9482048
00:17:04 14562 8192000000 10318866208 10768048128 1395337424 14.553 99 40 80 0 1424692752 19370624 9984704
00:18:27 69165 8192000000 10267501312 10720948224 1349356528 14.142 97 12 24 1 1371635936 18895152 3384256
00:21:32 39788 8192000000 10302287120 10761998336 1400603696 14.600 100 9 18 0 1416791440 13953712 2234032
00:23:18 69276 8192000000 10313466304 10763141120 1385261024 14.464 99 34 69 1 1413460928 19947584 8252320
00:25:28 29215 8192000000 10298256384 10757685248 1388469504 14.492 100 12 24 1 1404218784 12777056 2972224
00:26:58 93917 8192000000 10288174336 10758254592 1379421760 14.411 98 13 26 0 1392004816 9860480 2722576
00:29:32 62783 8192000000 10302398656 10762731520 1388053776 14.488 99 13 26 0 1404371184 13389088 2928320
00:31:42 48396 8192000000 10309016608 10774732800 1391692144 14.521 99 14 28 1 1410231232 14914896 3624192
00:33:39 53677 8192000000 10303346288 10756222976 1356937472 14.210 97 11 22 1 1369608480 9612976 3058032
00:35:55 55738 8192000000 10295708720 10755657728 1358766160 14.226 97 17 34 0 1372037648 9613120 3658368
00:37:51 76984 8192000000 10274376400 10730094592 1332866144 13.993 96 15 28 1 1349641968 13471840 3303984
00:40:12 138000 8192000000 10302435104 10763157504 1278436640 13.499 92 10 20 0 1301436720 20817744 2182336
00:43:30 115607 8192000000 10260038464 10702016512 1309351040 13.780 96 14 28 2 1332396576 20090544 2954992
00:46:31 17564 8192000000 10258612240 10695385088 1338798800 14.047 99 51 102 1 1360345312 10042832 11503680
00:47:55 57342 8192000000 10234054624 10692067328 1321736384 13.892 98 12 24 0 1334705616 10047184 2922048
00:49:53 71767 8192000000 10233124464 10683031552 1324654768 13.919 98 19 38 0 1336391008 7132960 4603280
00:52:23 54121 8192000000 10249805008 10700242944 1349644928 14.144 100 10 20 0 1358658208 6847056 2166224
00:54:17 66315 8192000000 10290864832 10746421248 1364615264 14.279 99 20 41 1 1383867808 14435520 4817024
00:56:27 76741 8192000000 10289725328 10756300800 1368764176 14.316 99 12 24 0 1391167296 19486944 2916176
00:59:01 79737 8192000000 10278833520 10735108096 1377912432 14.398 100 15 30 2 1391525296 9974736 3638128
01:01:22 16481 8192000000 10302960512 10735321088 1359393776 14.232 98 64 126 0 1385417264 11705440 14318048
01:02:41 90977 8192000000 10295594384 10747269120 1369008560 14.318 99 18 36 0 1383283904 10467632 3807712
01:05:12 104600 8192000000 10295475712 10746789888 1375286864 14.374 99 27 54 0 1395800224 13874160 6639200
01:08:26 69178 8192000000 10299737376 10765492224 1377790032 14.397 98 15 30 0 1398034672 16710080 3534560
01:10:36 77979 8192000000 10284193264 10748686336 1370924896 14.335 98 9 18 0 1385771952 12915600 1931456
01:13:00 71333 8192000000 10299513376 10767998976 1350833008 14.155 96 14 28 2 1368846176 14797888 3215280
01:15:14 81475 8192000000 10331261696 10789515264 1390700880 14.512 98 17 33 2 1409886032 14675280 4509872
01:17:36 75277 8192000000 10333162912 10795786240 1381637344 14.431 98 17 33 1 1400859584 15577120 3645120
01:20:02 114277 8192000000 10308392944 10764791808 1353706224 14.181 96 17 34 0 1367371040 10136064 3528752
01:23:00 73721 8192000000 10304755328 10756956160 1373858320 14.362 98 18 36 0 1388288672 10648848 3781504
01:25:15 42416 8192000000 10299825696 10735169536 1323026208 13.904 95 39 78 2 1340728656 8986624 8715824
01:27:04 79589 8192000000 10285998432 10746757120 1366797216 14.298 99 7 14 0 1384661376 16372640 1491520
01:29:42 4099 8192000000 10285104320 10731876352 1377115984 14.391 100 46 93 1 1396310496 9245456 9949056
01:30:47 41550 8192000000 10244959392 10712174592 1331749632 13.983 97 6 12 0 1341594656 8522752 1322272
01:32:28 55471 8192000000 10242798592 10701447168 1331239152 13.978 98 11 23 0 1340341904 6720288 2382464
01:34:24 102200 8192000000 10255328464 10707292160 1312221024 13.806 96 12 24 0 1326083408 11123312 2739072
01:37:07 37454 8192000000 10232396560 10676105216 1281481200 13.527 95 17 34 0 1297415408 11468880 4465328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 27-10-2021 01:39:13 to 27-10-2021 01:39:27.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-10-2021 01:39:28 to 27-10-2021 01:39:30.

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          242682
MON$OLDEST_ACTIVE               244256
MON$OLDEST_SNAPSHOT             244256
MON$NEXT_TRANSACTION            244256
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                       2035168
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.33524 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33524 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33524 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33524 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 ---
:329: 
:330: mscs-test-161.budzdorov.ru    Wed Oct 27 01:37:52 2021
:331:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:332: 
--- end of diff output ---

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

27.10.21 01:39:31. 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-10-27 01:39:34 No severe PSQL-related problems occured
27.10.21 01:39:35. End of report.