Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.09.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 15949400 kB
MemAvailable 30781920 kB
Buffers 0 kB
Cached 15704340 kB
SwapCached 416 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-18 01:37:50
DTS_END                         2021-09-18 01:37:50.4060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48442
TRN_ID                          242902
ATT_ID                          730
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: 9621.86 23640 1154623
customer order: creation 170.07 381 20408
customer order: refuse 15.21 359 1825
order to supplier: creation 45.69 1385 5483
order to supplier: removal 3.67 101324 440
invoice (draft): creation 46.51 3451 5581
invoice (draft): removal 55.41 805 6649
invoice accept: apply 43.04 16269 5165
invoice accept: cancel 49.78 792 5974
customer reserve: creation 3998.13 160 479776
customer reserve: removal 4890.87 60 586904
realization accept: apply 84.57 169 10148
realization accept: cancel 89.95 108 10794
payment from customer: creation 58.66 113 7039
payment from customer: removal 5.44 58 653
payment to supplier: creation 49.16 123 5899
payment to supplier: removal 3.38 67 405
service: total inventory turnovers 4.97 1210 597
service: total monetary turnovers 6.37 27 764
service: aggregate perf. data 0.96 18279 115
service: refresh index statistics 0.03 327655 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-09-2021 01:38:57 to 18-09-2021 01:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1864
TEST_TIME 2 2813
TEST_TIME 3 3661
TEST_TIME 4 4638
TEST_TIME 5 4630
TEST_TIME 6 4373
TEST_TIME 7 4702
TEST_TIME 8 4816
TEST_TIME 9 5446
TEST_TIME 10 5520
TEST_TIME 11 5820
TEST_TIME 12 6068
TEST_TIME 13 6395
TEST_TIME 14 6288
TEST_TIME 15 6369
TEST_TIME 16 6680
TEST_TIME 17 6639
TEST_TIME 18 6867
TEST_TIME 19 7129
TEST_TIME 20 7460
TEST_TIME 21 7693
TEST_TIME 22 8255
TEST_TIME 23 8590
TEST_TIME 24 9240
TEST_TIME 25 9654
TEST_TIME 26 10200
TEST_TIME 27 10529
TEST_TIME 28 10530
TEST_TIME 29 10389
TEST_TIME 30 10094
TEST_TIME 31 10037
TEST_TIME 32 10086
TEST_TIME 33 10094
TEST_TIME 34 9990
TEST_TIME 35 9889
TEST_TIME 36 9851
TEST_TIME 37 9769
TEST_TIME 38 9766
TEST_TIME 39 9702
TEST_TIME 40 9690
TEST_TIME 41 9560
TEST_TIME 42 9571
TEST_TIME 43 9745
TEST_TIME 44 9844
TEST_TIME 45 9778
TEST_TIME 46 9922
TEST_TIME 47 9969
TEST_TIME 48 9874
TEST_TIME 49 9991
TEST_TIME 50 10054
TEST_TIME 51 9996
TEST_TIME 52 10135
TEST_TIME 53 10026
TEST_TIME 54 10129
TEST_TIME 55 10075
TEST_TIME 56 10150
TEST_TIME 57 9991
TEST_TIME 58 9950
TEST_TIME 59 9951
TEST_TIME 60 9926
TEST_TIME 61 9866
TEST_TIME 62 9925
TEST_TIME 63 9851
TEST_TIME 64 9744
TEST_TIME 65 9698
TEST_TIME 66 9676
TEST_TIME 67 9702
TEST_TIME 68 9746
TEST_TIME 69 9712
TEST_TIME 70 9743
TEST_TIME 71 9727
TEST_TIME 72 9746
TEST_TIME 73 9772
TEST_TIME 74 9752
TEST_TIME 75 9789
TEST_TIME 76 9878
TEST_TIME 77 9871
TEST_TIME 78 9943
TEST_TIME 79 9938
TEST_TIME 80 9951
TEST_TIME 81 9914
TEST_TIME 82 9803
TEST_TIME 83 9789
TEST_TIME 84 9724
TEST_TIME 85 9753
TEST_TIME 86 9717
TEST_TIME 87 9668
TEST_TIME 88 9694
TEST_TIME 89 9653
TEST_TIME 90 9589
TEST_TIME 91 9634
TEST_TIME 92 9659
TEST_TIME 93 9674
TEST_TIME 94 9670
TEST_TIME 95 9678
TEST_TIME 96 9664
TEST_TIME 97 9774
TEST_TIME 98 9731
TEST_TIME 99 9750
TEST_TIME 100 9784
TEST_TIME 101 9768
TEST_TIME 102 9773
TEST_TIME 103 9786
TEST_TIME 104 9857
TEST_TIME 105 9844
TEST_TIME 106 9763
TEST_TIME 107 9696
TEST_TIME 108 9697
TEST_TIME 109 9690
TEST_TIME 110 9668
TEST_TIME 111 9630
TEST_TIME 112 9592
TEST_TIME 113 9573
TEST_TIME 114 9552
TEST_TIME 115 9492
TEST_TIME 116 9567
TEST_TIME 117 9557
TEST_TIME 118 9559
TEST_TIME 119 9558
TEST_TIME 120 9622

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-09-2021 01:39:01 to 18-09-2021 01:39:04.

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 1599129 1599129 0 0.00 0 21182 6 0 0
doc_list_biud 1599129 1599129 0 0.00 0 54426 28 0 0
sp_add_invoice_to_stock 5236 5165 71 1.35 35 168599 16269 0 71
sp_cancel_adding_invoice 5974 5974 0 0.00 4 70874 793 0 0
sp_cancel_client_order 1825 1825 0 0.00 4 18732 360 0 0
sp_cancel_customer_reserve 586904 586904 0 0.00 1 54428 60 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 1105 58 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 2259 68 0 0
sp_cancel_supplier_invoice 6653 6649 4 0.06 2 16723 805 0 4
sp_cancel_supplier_order 440 440 0 0.00 6 1295727 101324 0 0
sp_cancel_write_off 10794 10794 0 0.00 1 10672 108 0 0
sp_client_order 20408 20408 0 0.00 3 48744 381 0 0
sp_customer_reserve 479776 479776 0 0.00 0 52283 161 0 0
sp_fill_shopping_cart 31455 31455 0 0.00 1 13801 38 0 0
sp_fill_shopping_cart_clo_res 479775 479775 0 0.00 0 18910 12 0 0
sp_get_clo_for_invoice 5164 5164 0 0.00 0 39145 268 0 0
sp_kill_cost_storno 17826 17826 0 0.00 0 8524 53 0 0
sp_kill_qstorno_ret_qs2qd 1191691 1191691 0 0.00 0 54421 33 0 0
sp_kill_qty_storno 627898 627898 0 0.00 0 54425 70 0 0
sp_lock_selected_doc 21119 21119 0 0.00 0 7317 4 0 0
sp_make_cost_storno 28250 28250 0 0.00 0 18052 53 0 0
sp_multiply_rows_for_pdistr 28250 28250 0 0.00 0 8018 26 0 0
sp_multiply_rows_for_qdistr 20408 20408 0 0.00 0 48736 162 0 0
sp_pay_from_customer 7039 7039 0 0.00 4 21189 113 0 0
sp_pay_to_supplier 5899 5899 0 0.00 5 13079 124 0 0
sp_payment_common 12938 12938 0 0.00 2 9866 25 0 0
sp_qd_handle_on_cancel_clo 1825 1825 0 0.00 0 18722 335 0 0
sp_qd_handle_on_invoice_upd_sts 11138 11138 0 0.00 0 31260 306 0 0
sp_qd_handle_on_reserve_upd_sts 10148 10148 0 0.00 0 17160 61 0 0
sp_reserve_write_off 10148 10148 0 0.00 6 17189 170 0 0
sp_supplier_invoice 5581 5581 0 0.00 6 89244 3452 0 0
sp_supplier_order 5483 5483 0 0.00 6 31974 1385 0 0
srv_aggregate_perf_data 115 115 0 0.00 3219 246624 18280 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2049 151722 47765 0 0
srv_find_qd_qs_mism 1106523 1106523 0 0.00 0 12677 5 0 0
srv_make_invnt_saldo 597 597 0 0.00 37 78070 1211 0 0
srv_make_money_saldo 764 764 0 0.00 1 4410 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 175322 407368 327655 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5165 5165 0 0.00 0 8613 152 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 3750 97 0 0
t$perf-norm:sp_cancel_client_order 1825 1825 0 0.00 0 12898 31 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1056 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 4632 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 449 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 3079 31 0 0
t$perf-norm:sp_cancel_supplier_order 440 440 0 0.00 0 23119 1687 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 7103 31 0 0
t$perf-norm:sp_client_order 20408 20408 0 0.00 0 16765 21 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 4734 13 0 0
t$perf-norm:sp_pay_from_customer 7039 7039 0 0.00 0 9593 14 0 0
t$perf-norm:sp_pay_to_supplier 5899 5899 0 0.00 0 4773 13 0 0
t$perf-norm:sp_reserve_write_off 10148 10148 0 0.00 0 10568 13 0 0
t$perf-norm:sp_supplier_invoice 5581 5581 0 0.00 0 5848 20 0 0
t$perf-norm:sp_supplier_order 5483 5483 0 0.00 0 4982 19 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 489 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 508 10 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 964 27 0 0
t$perf-norm:srv_make_money_saldo 764 764 0 0.00 0 699 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 67 19 0 0
v_add_invoice_to_stock 10470 10328 142 1.35 0 10096 21 0 142
v_all_customers 36944 36944 0 0.00 0 19 1 0 0
v_all_suppliers 30932 30932 0 0.00 0 51 1 0 0
v_all_wares 40816 40816 0 0.00 0 70 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 417 6 0 0
v_cancel_client_order 3650 3650 0 0.00 0 2267 5 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 1099 4 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1159 5 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1113 4 0 0
v_cancel_supplier_invoice 850 842 8 0.94 0 333 13 0 8
v_cancel_supplier_order 880 880 0 0.00 0 7105 19 0 0
v_cancel_write_off 1970 1970 0 0.00 0 2307 15 0 0
v_max_id_clo_ord 5483 5483 0 0.00 0 13782 4 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 1630 15 0 0
v_max_id_ord_sup 5564 5564 0 0.00 0 2780 3 0 0
v_max_non_paid_invoice 1480 1480 0 0.00 0 5454 8 0 0
v_max_non_paid_realizn 3837 3837 0 0.00 0 3412 4 0 0
v_min_id_clo_ord 5483 5483 0 0.00 0 7139 11 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 1558 6 0 0
v_min_id_ord_sup 5564 5564 0 0.00 0 4467 10 0 0
v_min_non_paid_invoice 5899 5899 0 0.00 0 6771 13 0 0
v_min_non_paid_realizn 7039 7039 0 0.00 0 9861 17 0 0
v_our_firm 10276 10276 0 0.00 0 15 1 0 0
v_reserve_write_off 20296 20296 0 0.00 0 7120 6 0 0
x_lock_depdocs_on_canc_invoice 12202 12202 0 0.00 0 17690 120 0 0
x_lock_depdocs_on_canc_sup_ord 440 440 0 0.00 0 6130 309 0 0
x_make_qstorno_1000_1200 5483 5483 0 0.00 1 31943 1287 0 0
x_make_qstorno_1200_2000 5581 5581 0 0.00 3 88541 3332 0 0
x_make_qstorno_2100_3300 479776 479776 0 0.00 0 52281 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-09-2021 01:39:05 to 18-09-2021 01:39:09.

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 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:55 20860 8192000000 10380772048 10697797632 1432489872 14.883 100 91 182 0 1468315216 6200416 29624928
23:10:19 21010 8192000000 10429663520 10759643136 1481219952 15.312 100 83 166 0 1516208624 8344928 26643744
23:11:46 18994 8192000000 10480517424 10817880064 1532649328 15.760 100 87 174 0 1569845760 9953344 27243088
23:13:09 17107 8192000000 10551024096 10909106176 1605977216 16.390 100 72 144 3 1638360240 10755792 21627232
23:14:29 7728 8192000000 10625737760 10994507776 1691002880 17.110 100 70 141 0 1722145696 10906240 20236576
23:15:38 7251 8192000000 10708843712 11086671872 1767540144 17.747 100 84 168 0 1808146928 17325744 23281040
23:16:49 6540 8192000000 10746349488 11133353984 1807070656 18.072 100 81 161 1 1843171680 13805264 22295760
23:17:57 2088 8192000000 10803102144 11189186560 1859680768 18.501 100 94 187 2 1900529200 13885504 26962928
23:19:00 1409 8192000000 10809770336 11196850176 1863484848 18.532 100 73 149 2 1894574224 10019536 21069840
23:20:02 8963 8192000000 10769205280 11200147456 1871683504 18.598 100 38 75 1 1889804640 7654400 10466736
23:21:14 7380 8192000000 10788668512 11217244160 1881183168 18.675 100 20 40 1 1893649824 7032032 5434624
23:22:23 16828 8192000000 10798816944 11236286464 1894908688 18.785 100 18 35 2 1905750864 5723408 5118768
23:23:41 26927 8192000000 10815138496 11250511872 1901040064 18.835 100 21 42 0 1913295024 6034816 6220144
23:25:09 7988 8192000000 10826016272 11260678144 1912855872 18.930 100 27 54 0 1927068304 5878944 8333488
23:26:19 5235 8192000000 10818869456 11252965376 1906886416 18.882 100 26 50 2 1920157168 6313776 6956976
23:27:24 62368 8192000000 10781115664 11210485760 1820830288 18.184 96 32 62 1 1835409728 6117184 8462256
23:29:29 5891 8192000000 10719831136 11138027520 1763696240 17.715 96 23 47 0 1775657584 5678192 6283152
23:30:38 5960 8192000000 10715372288 11125911552 1728076800 17.419 95 26 53 2 1744948768 9469664 7402304
23:31:45 6638 8192000000 10717292656 11135401984 1804827984 18.054 100 20 40 1 1820709904 10047952 5833968
23:32:52 45802 8192000000 10739299616 11171622912 1788264384 17.918 97 29 58 2 1802661280 5469296 8927600
23:34:39 9109 8192000000 10773556640 11202826240 1861712528 18.517 100 30 61 1 1876407664 6611904 8083232
23:35:51 6212 8192000000 10798300832 11225153536 1876437920 18.636 100 16 32 0 1890885664 10085168 4362576
23:37:01 22279 8192000000 10820858720 11246882816 1904351152 18.861 100 26 52 1 1916301152 5182096 6767904
23:38:27 31733 8192000000 10846775360 11274743808 1921473696 18.999 100 35 71 1 1938432512 6954048 10004768
23:40:05 9269 8192000000 10889667888 11307642880 1947528048 19.207 100 59 117 1 1976276384 11629280 17119056
23:41:19 5654 8192000000 10897263344 11330240512 1964948496 19.345 100 47 92 2 1993124864 14150128 14026240
23:42:31 14572 8192000000 10924841952 11354050560 1982528576 19.485 100 44 88 0 2009619600 13538864 13552160
23:43:51 15987 8192000000 10933314928 11373686784 1998093392 19.608 100 44 86 0 2032118672 20143248 13882032
23:45:12 10181 8192000000 10958369152 11388432384 1991296064 19.554 99 56 112 2 2028923392 21670368 15956960
23:46:26 4451 8192000000 10951656624 11373785088 1978766448 19.455 99 66 132 0 2023732032 24965328 20000256
23:47:35 14767 8192000000 10968345104 11410333696 2013769696 19.731 100 48 96 0 2061518144 33027888 14720560
23:48:58 3343 8192000000 10957237488 11389505536 1997045936 19.599 100 64 127 1 2048062736 31065488 19951312
23:50:09 7307 8192000000 10947544880 11369598976 1981135584 19.474 100 73 142 2 2038202736 34810672 22256480
23:51:21 13848 8192000000 10938231840 11364917248 1967834608 19.368 100 57 114 1 2023702544 37921424 17946512
23:52:40 3546 8192000000 10931550272 11353980928 1937726976 19.129 98 70 140 3 1995994496 35917040 22350480
23:53:47 5219 8192000000 10950353760 11371704320 1976662624 19.438 100 71 141 2 2034026112 36213440 21150048
23:54:57 2048 8192000000 10991259408 11410845696 2006885520 19.677 100 85 168 2 2076844512 42387648 27571344
23:56:07 5461 8192000000 10970052976 11388280832 1976698928 19.439 100 67 135 1 2044080752 45950944 21430880
23:57:19 5501 8192000000 10933990912 11358547968 1950962976 19.234 100 61 122 0 2018453088 47231040 20259072
23:58:28 18079 8192000000 10930616384 11376553984 1945831312 19.193 99 35 70 1 2006710016 48549312 12329392
23:59:56 11092 8192000000 10903308816 11343818752 1919323152 18.981 99 34 68 0 1976644416 46009232 11312032
00:01:29 32736 8192000000 10862573760 11322241024 1851281120 18.433 96 29 58 0 1908397088 47226528 9889440
00:03:05 40803 8192000000 10870583136 11328536576 1920980320 18.995 100 18 35 0 1949128752 22181248 5967184
00:05:17 40178 8192000000 10867249872 11312873472 1914380960 18.942 99 17 31 1 1930733360 11565776 4786624
00:07:04 95979 8192000000 10856431648 11317178368 1906518704 18.879 98 37 74 0 1924984992 7858320 10607968
00:09:44 72195 8192000000 10882914208 11345354752 1928588800 19.056 98 17 34 0 1941894848 8803760 4502288
00:11:58 30790 8192000000 10912889296 11375390720 1986525904 19.516 100 18 36 0 2000506192 9096208 4884080
00:13:38 55682 8192000000 10952229408 11405496320 1989060944 19.536 99 26 50 0 2010843440 14412672 7369824
00:15:38 30652 8192000000 10939570064 11407069184 2007652880 19.683 100 29 58 1 2035119680 18503904 8962896
00:17:13 54396 8192000000 10943625008 11405398016 2004873568 19.661 100 16 32 0 2026366368 16552576 4940224
00:19:11 51297 8192000000 10927372864 11396599808 1992303584 19.562 99 18 36 0 2017580672 18942432 6334656
00:21:24 36029 8192000000 10953530784 11405111296 1988849504 19.535 99 35 69 0 2008436224 9802864 9783856
00:23:00 57136 8192000000 10917039952 11402919936 1981601552 19.477 99 10 20 0 2005382080 20371632 3408896
00:24:58 21115 8192000000 10940219712 11400540160 2003585616 19.651 100 37 74 0 2022571584 8891952 10094016
00:26:23 62019 8192000000 10921647424 11397136384 1956405968 19.277 98 17 34 1 1978032352 17093232 4533152
00:28:29 139931 8192000000 10929262096 11403333632 1919874400 18.986 96 12 24 0 1943379712 20325536 3179776
00:31:51 56145 8192000000 10933903520 11394711552 1978302848 19.451 99 16 32 0 1996067248 13060400 4704000
00:33:48 110602 8192000000 10951684368 11413696512 2004050704 19.655 100 22 43 1 2027660192 17592864 6016624
00:36:47 53399 8192000000 10935467008 11412865024 1985378176 19.507 99 21 42 0 2010435248 19205872 5851200
00:38:41 48307 8192000000 10917699248 11396964352 1982593296 19.485 100 22 44 2 2010378016 21371664 6413056
00:40:30 52235 8192000000 10903479696 11381411840 1955383168 19.269 98 12 24 0 1969208912 10445120 3380624
00:42:24 35822 8192000000 10887607040 11357741056 1922272832 19.005 97 12 25 1 1938240704 12617984 3349888
00:44:08 33682 8192000000 10878251312 11347005440 1921863216 19.002 98 25 48 1 1940232064 11775392 6593456
00:45:42 44793 8192000000 10865760256 11337641984 1892462592 18.766 97 9 18 2 1907370960 12321968 2586400
00:47:28 82850 8192000000 10868560928 11326730240 1837855008 18.323 94 15 30 0 1851055904 8946352 4254544
00:49:52 80187 8192000000 10859240336 11326652416 1841647888 18.354 95 14 28 0 1865232672 19452912 4131872
00:52:12 78636 8192000000 10865183776 11333570560 1898248064 18.812 98 23 46 1 1931468000 26811664 6408272
00:54:34 34118 8192000000 10849686368 11305586688 1881967616 18.681 98 24 48 0 1898431472 9688912 6774944
00:56:09 67933 8192000000 10866651248 11330453504 1919744016 18.985 100 9 18 0 1932377552 10378304 2255232
00:58:17 99532 8192000000 10865039376 11320389632 1915828864 18.953 99 14 27 1 1931039328 11334688 3875776
01:00:58 67536 8192000000 10891531536 11347955712 1950801936 19.233 100 34 68 1 1974982448 13891680 10288832
01:03:07 40921 8192000000 10855081872 11325841408 1903963888 18.858 98 15 31 0 1922595696 14631264 4000544
01:04:48 51713 8192000000 10871116800 11342540800 1929853728 19.066 99 18 36 0 1949354960 14022800 5478432
01:06:41 86840 8192000000 10900534800 11377012736 1963808464 19.336 100 18 36 1 1980501872 11723776 4969632
01:09:07 89755 8192000000 10905619648 11375181824 1954500624 19.262 99 18 37 0 1973089088 13661872 4926592
01:11:38 37636 8192000000 10923392608 11404279808 1991464608 19.555 100 19 38 2 2013577408 16929344 5183456
01:13:15 42452 8192000000 10954259952 11403558912 1986491648 19.516 99 35 70 0 2015929536 18736128 10701760
01:15:00 21318 8192000000 10923278192 11389480960 1997432576 19.602 100 38 78 2 2015460048 6274704 11752768
01:16:30 145254 8192000000 10916034656 11391426560 1908050016 18.891 95 22 44 0 1923810720 9875168 5885536
01:19:59 80598 8192000000 10895115904 11362836480 1838829424 18.331 92 20 40 0 1856793568 12356048 5608096
01:22:20 151722 8192000000 10900463856 11366019072 1901826848 18.841 96 13 26 1 1920075872 14664864 3584160
01:26:28 68537 8192000000 10895337104 11380871168 1956280128 19.276 99 12 24 0 1968181696 8256400 3645168
01:28:45 59962 8192000000 10896537264 11373154304 1940721296 19.153 98 12 24 1 1957544160 13452352 3370512
01:30:45 66919 8192000000 10878220336 11354038272 1899860464 18.825 96 11 22 2 1918427712 15554032 3013216
01:33:05 39578 8192000000 10852066432 11331485696 1892555104 18.766 97 17 35 1 1909761376 11886608 5319664
01:34:45 59071 8192000000 10868343184 11340185600 1881865824 18.680 97 23 46 1 1896729824 8741184 6122816
01:36:48 65866 8192000000 10317609296 10746961920 1397637872 14.574 72 14 28 0 1415507168 14100192 3769104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 71 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-09-2021 01:39:27 to 18-09-2021 01:39:29.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243238
MON$OLDEST_ACTIVE               243239
MON$OLDEST_SNAPSHOT             243239
MON$NEXT_TRANSACTION            243239
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                       2039136
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.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 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'

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