Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.09.2021 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15828928 kB
MemAvailable 30866632 kB
Buffers 0 kB
Cached 15871728 kB
SwapCached 388 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-09-12 19:37:46
DTS_END                         2021-09-12 19:37:46.3320
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/52108
TRN_ID                          241622
ATT_ID                          678
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: 9439.58 28019 1132749
customer order: creation 164.93 373 19791
customer order: refuse 14.47 353 1736
order to supplier: creation 45.71 1332 5485
order to supplier: removal 3.28 118899 394
invoice (draft): creation 45.77 3703 5492
invoice (draft): removal 53.50 867 6420
invoice accept: apply 41.70 18564 5004
invoice accept: cancel 49.10 883 5892
customer reserve: creation 3915.40 179 469848
customer reserve: removal 4813.60 63 577632
realization accept: apply 82.11 213 9853
realization accept: cancel 83.78 128 10054
payment from customer: creation 58.24 141 6989
payment from customer: removal 5.68 96 681
payment to supplier: creation 47.63 185 5715
payment to supplier: removal 3.35 107 402
service: total inventory turnovers 4.25 1392 510
service: total monetary turnovers 6.21 65 745
service: aggregate perf. data 0.85 20624 102
service: refresh index statistics 0.03 392205 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3233
TEST_TIME 2 3883
TEST_TIME 3 4833
TEST_TIME 4 4155
TEST_TIME 5 4539
TEST_TIME 6 5567
TEST_TIME 7 5461
TEST_TIME 8 5529
TEST_TIME 9 5626
TEST_TIME 10 5912
TEST_TIME 11 6153
TEST_TIME 12 6092
TEST_TIME 13 6233
TEST_TIME 14 6491
TEST_TIME 15 6553
TEST_TIME 16 6727
TEST_TIME 17 6954
TEST_TIME 18 7095
TEST_TIME 19 7636
TEST_TIME 20 7979
TEST_TIME 21 8301
TEST_TIME 22 8505
TEST_TIME 23 8458
TEST_TIME 24 8562
TEST_TIME 25 8760
TEST_TIME 26 8847
TEST_TIME 27 8807
TEST_TIME 28 9066
TEST_TIME 29 9112
TEST_TIME 30 10559
TEST_TIME 31 11055
TEST_TIME 32 10872
TEST_TIME 33 11040
TEST_TIME 34 11232
TEST_TIME 35 10930
TEST_TIME 36 10915
TEST_TIME 37 10935
TEST_TIME 38 10859
TEST_TIME 39 10778
TEST_TIME 40 10684
TEST_TIME 41 10573
TEST_TIME 42 10390
TEST_TIME 43 10348
TEST_TIME 44 10346
TEST_TIME 45 10239
TEST_TIME 46 10242
TEST_TIME 47 10212
TEST_TIME 48 10186
TEST_TIME 49 10234
TEST_TIME 50 10159
TEST_TIME 51 10224
TEST_TIME 52 10159
TEST_TIME 53 10043
TEST_TIME 54 9964
TEST_TIME 55 9850
TEST_TIME 56 9909
TEST_TIME 57 9845
TEST_TIME 58 9757
TEST_TIME 59 9726
TEST_TIME 60 9638
TEST_TIME 61 9752
TEST_TIME 62 9833
TEST_TIME 63 9821
TEST_TIME 64 9736
TEST_TIME 65 9735
TEST_TIME 66 9687
TEST_TIME 67 9642
TEST_TIME 68 9681
TEST_TIME 69 9643
TEST_TIME 70 9665
TEST_TIME 71 9626
TEST_TIME 72 9654
TEST_TIME 73 9629
TEST_TIME 74 9661
TEST_TIME 75 9663
TEST_TIME 76 9786
TEST_TIME 77 9805
TEST_TIME 78 9796
TEST_TIME 79 9824
TEST_TIME 80 9847
TEST_TIME 81 9838
TEST_TIME 82 9789
TEST_TIME 83 9720
TEST_TIME 84 9676
TEST_TIME 85 9665
TEST_TIME 86 9638
TEST_TIME 87 9588
TEST_TIME 88 9521
TEST_TIME 89 9472
TEST_TIME 90 9506
TEST_TIME 91 9476
TEST_TIME 92 9418
TEST_TIME 93 9474
TEST_TIME 94 9458
TEST_TIME 95 9491
TEST_TIME 96 9471
TEST_TIME 97 9519
TEST_TIME 98 9571
TEST_TIME 99 9613
TEST_TIME 100 9659
TEST_TIME 101 9657
TEST_TIME 102 9672
TEST_TIME 103 9703
TEST_TIME 104 9701
TEST_TIME 105 9692
TEST_TIME 106 9774
TEST_TIME 107 9736
TEST_TIME 108 9657
TEST_TIME 109 9632
TEST_TIME 110 9579
TEST_TIME 111 9583
TEST_TIME 112 9553
TEST_TIME 113 9547
TEST_TIME 114 9523
TEST_TIME 115 9515
TEST_TIME 116 9495
TEST_TIME 117 9474
TEST_TIME 118 9469
TEST_TIME 119 9465
TEST_TIME 120 9445

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-09-2021 19:39:46 to 12-09-2021 19:39:50.

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 1566194 1566194 0 0.00 0 26872 6 0 0
doc_list_biud 1566194 1566194 0 0.00 0 28390 30 0 0
sp_add_invoice_to_stock 5112 5004 108 2.11 31 232684 18565 0 108
sp_cancel_adding_invoice 5892 5892 0 0.00 4 63120 884 0 0
sp_cancel_client_order 1736 1736 0 0.00 4 13237 354 0 0
sp_cancel_customer_reserve 577632 577632 0 0.00 1 22132 63 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 5923 96 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 8924 107 0 0
sp_cancel_supplier_invoice 6428 6420 8 0.12 2 16672 868 0 8
sp_cancel_supplier_order 394 394 0 0.00 9 1058135 118899 0 0
sp_cancel_write_off 10054 10054 0 0.00 1 19762 129 0 0
sp_client_order 19791 19791 0 0.00 4 20195 373 0 0
sp_customer_reserve 469848 469848 0 0.00 0 181731 179 0 0
sp_fill_shopping_cart 30760 30760 0 0.00 1 21781 40 0 0
sp_fill_shopping_cart_clo_res 469841 469841 0 0.00 0 19843 13 0 0
sp_get_clo_for_invoice 4995 4995 0 0.00 0 31509 270 0 0
sp_kill_cost_storno 17029 17029 0 0.00 0 13793 67 0 0
sp_kill_qstorno_ret_qs2qd 1172132 1172132 0 0.00 0 22120 34 0 0
sp_kill_qty_storno 616976 616976 0 0.00 0 28389 74 0 0
sp_lock_selected_doc 20604 20604 0 0.00 0 8501 6 0 0
sp_make_cost_storno 27552 27552 0 0.00 0 26867 72 0 0
sp_multiply_rows_for_pdistr 27552 27552 0 0.00 0 26096 32 0 0
sp_multiply_rows_for_qdistr 19791 19791 0 0.00 0 18796 153 0 0
sp_pay_from_customer 6989 6989 0 0.00 4 39288 142 0 0
sp_pay_to_supplier 5715 5715 0 0.00 5 41441 185 0 0
sp_payment_common 12704 12704 0 0.00 1 27236 46 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 13207 322 0 0
sp_qd_handle_on_invoice_upd_sts 10887 10887 0 0.00 0 28386 383 0 0
sp_qd_handle_on_reserve_upd_sts 9853 9853 0 0.00 0 21110 69 0 0
sp_reserve_write_off 9853 9853 0 0.00 7 22582 214 0 0
sp_supplier_invoice 5492 5492 0 0.00 7 79427 3704 0 0
sp_supplier_order 5485 5485 0 0.00 5 34710 1332 0 0
srv_aggregate_perf_data 102 102 0 0.00 2653 263233 20624 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1734 129622 47554 0 0
srv_find_qd_qs_mism 1084695 1084695 0 0.00 0 20426 5 0 0
srv_make_invnt_saldo 510 510 0 0.00 59 134978 1393 0 0
srv_make_money_saldo 745 745 0 0.00 1 21486 66 0 0
srv_recalc_idx_stat 4 4 0 0.00 64958 606031 392206 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 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 5004 5004 0 0.00 0 8744 188 0 0
t$perf-norm:sp_cancel_adding_invoice 460 460 0 0.00 0 6389 109 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 6303 14 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2769 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 1334 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 823 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 2129 17 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 27278 2101 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 9649 26 0 0
t$perf-norm:sp_client_order 19791 19791 0 0.00 0 13211 22 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 2302 10 0 0
t$perf-norm:sp_pay_from_customer 6989 6989 0 0.00 0 5502 14 0 0
t$perf-norm:sp_pay_to_supplier 5715 5715 0 0.00 0 4091 10 0 0
t$perf-norm:sp_reserve_write_off 9853 9853 0 0.00 0 11512 17 0 0
t$perf-norm:sp_supplier_invoice 5492 5492 0 0.00 0 5743 23 0 0
t$perf-norm:sp_supplier_order 5485 5485 0 0.00 0 5212 22 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 547 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2145 41 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 965 27 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 3847 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 4 0 0
v_add_invoice_to_stock 10202 9986 216 2.11 0 7987 21 0 216
v_all_customers 36156 36156 0 0.00 0 108 1 0 0
v_all_suppliers 30204 30204 0 0.00 0 48 1 0 0
v_all_wares 39582 39582 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 920 920 0 0.00 0 3477 13 0 0
v_cancel_client_order 3472 3472 0 0.00 0 4246 8 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 1302 5 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 4198 12 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 3911 11 0 0
v_cancel_supplier_invoice 768 752 16 2.08 0 3502 24 0 16
v_cancel_supplier_order 788 788 0 0.00 0 721 5 0 0
v_cancel_write_off 2096 2096 0 0.00 0 2984 20 0 0
v_max_id_clo_ord 5485 5485 0 0.00 0 204 1 0 0
v_max_id_clo_res 949 949 0 0.00 0 5751 23 0 0
v_max_id_ord_sup 5484 5484 0 0.00 0 3417 4 0 0
v_max_non_paid_invoice 1582 1582 0 0.00 0 1230 3 0 0
v_max_non_paid_realizn 3695 3695 0 0.00 0 13713 10 0 0
v_min_id_clo_ord 5485 5485 0 0.00 0 5534 7 0 0
v_min_id_clo_res 949 949 0 0.00 0 3394 7 0 0
v_min_id_ord_sup 5484 5484 0 0.00 0 9524 9 0 0
v_min_non_paid_invoice 5715 5715 0 0.00 0 21597 35 0 0
v_min_non_paid_realizn 6989 6989 0 0.00 0 21568 27 0 0
v_our_firm 10014 10014 0 0.00 0 233 1 0 0
v_reserve_write_off 19706 19706 0 0.00 0 4473 8 0 0
x_lock_depdocs_on_canc_invoice 11936 11936 0 0.00 0 13740 131 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 11741 427 0 0
x_make_qstorno_1000_1200 5485 5485 0 0.00 1 34704 1243 0 0
x_make_qstorno_1200_2000 5492 5492 0 0.00 3 57642 3571 0 0
x_make_qstorno_2100_3300 469848 469848 0 0.00 0 181728 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-09-2021 19:39:51 to 12-09-2021 19:39:55.

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:07:50 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:50 38300 8192000000 10394694400 10716295168 1433335584 14.891 100 91 182 0 1470047456 6718656 29993216
17:10:36 15502 8192000000 10434719584 10772217856 1498485552 15.463 100 82 165 1 1533535440 8878624 26171264
17:12:00 11576 8192000000 10496470368 10837323776 1550568048 15.915 100 85 170 1 1586959968 10350608 26041312
17:13:14 13169 8192000000 10557230160 10911514624 1611388944 16.437 100 83 164 1 1647573264 11489552 24694768
17:14:29 6759 8192000000 10622876048 10992467968 1685866432 17.067 100 79 158 0 1721648784 12676304 23106048
17:15:40 6128 8192000000 10686444816 11064250368 1755072848 17.644 100 88 171 0 1791873152 13177888 23622416
17:16:48 3324 8192000000 10754448384 11137191936 1810103840 18.097 100 84 167 1 1846234112 13126384 23003888
17:17:54 1554 8192000000 10798298496 11183316992 1849834864 18.421 100 92 181 0 1889694992 14240528 25619600
17:18:58 7926 8192000000 10813716160 11203747840 1869739568 18.582 100 85 171 0 1904713968 11441168 23533232
17:20:06 8273 8192000000 10778134144 11203153920 1868433376 18.572 100 25 49 1 1882825056 7116704 7274976
17:21:15 11394 8192000000 10783444640 11213680640 1879850096 18.664 100 18 36 1 1891836384 6402848 5583440
17:22:27 12223 8192000000 10794897808 11230617600 1895095296 18.787 100 18 36 3 1906806912 6832000 4879616
17:23:39 54766 8192000000 10819608640 11261415424 1909754080 18.905 100 19 35 1 1920058016 5372576 4931360
17:25:45 3384 8192000000 10830084880 11260637184 1894342048 18.781 99 29 58 0 1909321376 6992656 7986672
17:26:49 4089 8192000000 10828469440 11255054336 1904783888 18.865 100 39 76 2 1922700384 6759136 11157360
17:27:56 11150 8192000000 10782317872 11216248832 1827902976 18.242 97 11 22 0 1835973584 5116256 2954352
17:29:10 41372 8192000000 10683952720 11103911936 1594211472 16.290 86 14 28 2 1602701424 4852544 3637408
17:30:52 5612 8192000000 10704646016 11121086464 1763467616 17.713 98 27 53 1 1781496672 10923392 7105664
17:31:58 8364 8192000000 10741230672 11157188608 1820240480 18.180 100 32 64 5 1835892224 6091600 9560144
17:33:12 17065 8192000000 10760313136 11190136832 1845926256 18.389 100 25 50 1 1862603776 9537984 7139536
17:34:30 20710 8192000000 10764302272 11190874112 1816730112 18.151 98 24 46 4 1833136928 9827216 6579600
17:35:53 6618 8192000000 10787265776 11213246464 1869071552 18.577 100 42 85 2 1888424240 6865584 12487104
17:37:01 8738 8192000000 10805829952 11237285888 1891548096 18.758 100 19 38 0 1902480496 5923760 5008640
17:38:18 53226 8192000000 10858638928 11280908288 1925349088 19.030 100 40 80 0 1946230000 9631232 11249680
17:40:14 18136 8192000000 10882266800 11297792000 1932405024 19.086 100 34 69 4 1955273696 13130944 9737728
17:41:39 39236 8192000000 10887771616 11322585088 1950222736 19.228 100 44 88 0 1977490448 15157104 12110608
17:43:23 11938 8192000000 10914334512 11339419648 1964512912 19.342 100 61 120 2 2002081424 20604784 16963728
17:44:53 19369 8192000000 10917327152 11355586560 1972539952 19.406 100 47 92 0 2008911072 22187232 14183888
17:46:25 4840 8192000000 10939197568 11368132608 1972460416 19.405 99 59 116 1 2015329040 25395488 17473136
17:47:38 5624 8192000000 10941073744 11364802560 1980948240 19.472 100 65 130 0 2027400160 27617504 18834416
17:48:49 25171 8192000000 10946980192 11381473280 1994844928 19.582 100 56 110 1 2035998496 24683744 16469824
17:50:24 3011 8192000000 10953196416 11381231616 1992388928 19.563 100 67 132 0 2041995792 27711728 21895136
17:51:35 6013 8192000000 10962297216 11392270336 1996149424 19.592 100 63 125 1 2045181280 30156288 18875568
17:52:48 3010 8192000000 10958455312 11368615936 1973088272 19.410 100 87 171 3 2037522656 38529776 25904608
17:54:00 1728 8192000000 10946187888 11348463616 1949432448 19.222 99 85 170 1 2021457456 44505776 27519232
17:55:06 3602 8192000000 10954847152 11364102144 1956591568 19.279 100 79 158 2 2029766448 48304304 24870576
17:56:15 2384 8192000000 10963662448 11372474368 1957847744 19.289 100 78 155 1 2040806368 57939648 25018976
17:57:23 2343 8192000000 10992022224 11400495104 1979932976 19.464 100 82 165 1 2063877152 57214672 26729504
17:58:30 3427 8192000000 10996987040 11404132352 1991827936 19.558 100 78 155 0 2067313328 51891072 23594320
17:59:38 8306 8192000000 10977792880 11425026048 2000817776 19.629 100 42 83 1 2063567216 49109328 13640112
18:00:50 8235 8192000000 10975455360 11412451328 1975653984 19.430 99 43 86 1 2045601728 55598192 14349552
18:02:03 10238 8192000000 10983460832 11419852800 1989447616 19.539 100 46 93 0 2063201760 59103568 14650576
18:03:25 10007 8192000000 10974045456 11420770304 1991111248 19.553 100 41 80 1 2059605984 55434896 13059840
18:04:37 24521 8192000000 10946469104 11404001280 1952685024 19.248 99 26 52 0 2028687600 66587936 9414640
18:06:05 21309 8192000000 10973150256 11412840448 1952202288 19.244 100 29 58 3 2035541680 72150256 11189136
18:08:19 67259 8192000000 10899578192 11348185088 1949934896 19.226 100 8 15 0 1969493392 17276624 2281872
18:10:29 50709 8192000000 10904256256 11382591488 1951692304 19.240 99 9 18 1 1972668480 18421232 2554944
18:12:23 49754 8192000000 10914180576 11347767296 1945912624 19.194 99 49 95 0 1973446304 13798192 13735488
18:14:13 45212 8192000000 10875152160 11346452480 1901987232 18.842 97 11 22 0 1914904208 9847200 3069776
18:15:59 105358 8192000000 10885135568 11360669696 1909499728 18.903 97 14 28 2 1930241472 16760896 3980848
18:19:29 26804 8192000000 10931881600 11383250944 1990807536 19.550 100 46 93 1 2012843536 8630368 13405632
18:20:58 62566 8192000000 10916274336 11387486208 1944539696 19.183 98 13 26 1 1961946288 13443664 3962928
18:23:00 83503 8192000000 10903924896 11374432256 1928463984 19.055 97 13 26 1 1942566640 10937360 3165296
18:25:40 76103 8192000000 10905119712 11370442752 1944178288 19.180 98 17 34 0 1961937456 12708944 5050224
18:27:57 104176 8192000000 10924857952 11404320768 1978792288 19.455 99 16 32 1 1995953840 12803968 4357584
18:30:42 42386 8192000000 10914870704 11384832000 1977500208 19.445 99 34 68 0 1996725968 9509824 9715936
18:33:11 66968 8192000000 10924635344 11378630656 1949555536 19.223 98 26 52 0 1971389552 14312368 7521648
18:35:28 129622 8192000000 10951832080 11405488128 1937686112 19.128 97 27 53 0 1957257856 11168208 8403536
18:38:42 74060 8192000000 10913266368 11388796928 1931832224 19.082 97 23 46 0 1957867664 19794624 6240816
18:40:59 24872 8192000000 10912780912 11394412544 1987664448 19.525 100 10 20 1 2000639872 10186368 2789056
18:42:24 37702 8192000000 10896350224 11363446784 1911751680 18.921 96 15 29 0 1927619744 12014640 3853424
18:44:04 39400 8192000000 10897313952 11383181312 1974782976 19.423 100 19 38 0 1990261648 9871744 5606928
18:45:59 86225 8192000000 10910211552 11390431232 1934644080 19.104 97 18 36 0 1949952928 10171328 5137520
18:48:38 69795 8192000000 10870430864 11351793664 1890318144 18.748 96 8 16 1 1904477232 12316432 1842656
18:50:58 77331 8192000000 10902735152 11370807296 1934750208 19.105 98 20 40 0 1958094192 17571664 5772320
18:53:34 111083 8192000000 10894923904 11339784192 1835196736 18.302 94 30 59 2 1854793392 11309712 8286944
18:56:32 95767 8192000000 10903618576 11367288832 1943368448 19.174 99 13 26 0 1957522064 10600400 3553216
18:59:08 96421 8192000000 10894863664 11340525568 1916806416 18.961 98 29 58 0 1933497696 8678256 8013024
19:01:57 46556 8192000000 10883097008 11350519808 1935350480 19.110 99 30 60 0 1956967312 12885504 8731328
19:03:44 46364 8192000000 10874637168 11345821696 1925446080 19.030 98 16 32 1 1939737664 9421488 4870096
19:05:31 76734 8192000000 10877260256 11346436096 1904020960 18.859 97 13 26 1 1918400560 10639664 3739936
19:07:51 47562 8192000000 10874297168 11343605760 1926458672 19.039 98 14 28 0 1942069728 11711760 3899296
19:09:41 68866 8192000000 10873255232 11351498752 1931927792 19.082 98 17 34 1 1951277104 14876208 4473104
19:11:59 50637 8192000000 10910597504 11387142144 1960336752 19.309 99 21 42 2 1977198832 11092304 5769776
19:13:50 30020 8192000000 10915373376 11379007488 1931629552 19.080 97 32 64 0 1959004608 18305632 9069424
19:15:26 63063 8192000000 10915667936 11401863168 1939375808 19.142 97 25 50 0 1965432928 18744336 7312784
19:17:29 45657 8192000000 10920006304 11392016384 1962967072 19.330 99 20 40 0 1984178592 15094112 6117408
19:19:15 23874 8192000000 10941693456 11394015232 1991611584 19.557 100 41 83 3 2014515888 11390928 11513376
19:20:51 84402 8192000000 10926016160 11388489728 1915198464 18.948 96 18 36 0 1930139248 9847472 5093312
19:23:23 106149 8192000000 10904429952 11389501440 1906186240 18.876 96 17 34 0 1929275632 18244384 4845008
19:26:09 83783 8192000000 10898291200 11372621824 1935515504 19.111 98 17 34 0 1950185504 9595312 5074688
19:28:34 78916 8192000000 10909329200 11403329536 1967787536 19.368 99 7 14 1 1983624464 13952960 1883968
19:30:55 63429 8192000000 10903370480 11380531200 1948087920 19.211 98 14 28 0 1959561056 7648960 3824176
19:33:01 97779 8192000000 10885431344 11370266624 1923940144 19.018 97 18 37 0 1941448752 11624368 5884240
19:35:43 39756 8192000000 10873882448 11354554368 1899853344 18.825 97 15 29 1 1920570240 16625120 4091776
19:37:23 33517 8192000000 9140533552 9388691456 310807440 3.655 16 6 12 0 313445072 1418000 1219632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-09-2021 19:39:57 to 12-09-2021 19:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 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 12-09-2021 19:40:12 to 12-09-2021 19:40:14.

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          240380
MON$OLDEST_ACTIVE               241934
MON$OLDEST_SNAPSHOT             241934
MON$NEXT_TRANSACTION            241934
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                       2069408
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.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 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 ---
--- end of diff output ---

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

12.09.21 19:40:15. 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-09-12 19:40:17 No severe PSQL-related problems occured
12.09.21 19:40:19. End of report.