Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.09.2021 22: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 16100040 kB
MemAvailable 30795544 kB
Buffers 0 kB
Cached 15612528 kB
SwapCached 1628 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-24 22:37:55
DTS_END                         2021-09-24 22:37:55.5180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43684
TRN_ID                          234149
ATT_ID                          636
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: 9465.17 25483 1135821
customer order: creation 167.90 367 20148
customer order: refuse 14.46 367 1735
order to supplier: creation 46.21 1231 5545
order to supplier: removal 3.62 105060 434
invoice (draft): creation 45.53 3387 5463
invoice (draft): removal 54.77 849 6572
invoice accept: apply 41.44 18782 4973
invoice accept: cancel 48.45 784 5814
customer reserve: creation 3894.16 182 467299
customer reserve: removal 4863.37 61 583604
realization accept: apply 83.66 187 10039
realization accept: cancel 73.67 126 8840
payment from customer: creation 58.38 113 7006
payment from customer: removal 5.71 81 685
payment to supplier: creation 47.74 130 5729
payment to supplier: removal 3.63 100 435
service: total inventory turnovers 4.91 1235 589
service: total monetary turnovers 6.73 29 808
service: aggregate perf. data 0.83 23053 99
service: refresh index statistics 0.03 353535 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-09-2021 22:39:23 to 24-09-2021 22:39:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 993
TEST_TIME 2 1760
TEST_TIME 3 3144
TEST_TIME 4 4262
TEST_TIME 5 4502
TEST_TIME 6 4736
TEST_TIME 7 5394
TEST_TIME 8 5595
TEST_TIME 9 5593
TEST_TIME 10 5701
TEST_TIME 11 5685
TEST_TIME 12 5783
TEST_TIME 13 5717
TEST_TIME 14 5771
TEST_TIME 15 6054
TEST_TIME 16 6608
TEST_TIME 17 6811
TEST_TIME 18 7243
TEST_TIME 19 7165
TEST_TIME 20 7648
TEST_TIME 21 7802
TEST_TIME 22 8304
TEST_TIME 23 9041
TEST_TIME 24 9660
TEST_TIME 25 9681
TEST_TIME 26 9905
TEST_TIME 27 10767
TEST_TIME 28 10904
TEST_TIME 29 10748
TEST_TIME 30 10649
TEST_TIME 31 10452
TEST_TIME 32 10304
TEST_TIME 33 10388
TEST_TIME 34 10309
TEST_TIME 35 10270
TEST_TIME 36 10143
TEST_TIME 37 10080
TEST_TIME 38 10011
TEST_TIME 39 9944
TEST_TIME 40 9958
TEST_TIME 41 10005
TEST_TIME 42 9952
TEST_TIME 43 9986
TEST_TIME 44 9921
TEST_TIME 45 9860
TEST_TIME 46 9879
TEST_TIME 47 9843
TEST_TIME 48 9812
TEST_TIME 49 9815
TEST_TIME 50 9852
TEST_TIME 51 10028
TEST_TIME 52 9980
TEST_TIME 53 9895
TEST_TIME 54 9943
TEST_TIME 55 9982
TEST_TIME 56 10011
TEST_TIME 57 10045
TEST_TIME 58 10139
TEST_TIME 59 10087
TEST_TIME 60 10043
TEST_TIME 61 9949
TEST_TIME 62 9859
TEST_TIME 63 9852
TEST_TIME 64 9784
TEST_TIME 65 9762
TEST_TIME 66 9762
TEST_TIME 67 9707
TEST_TIME 68 9723
TEST_TIME 69 9626
TEST_TIME 70 9557
TEST_TIME 71 9496
TEST_TIME 72 9573
TEST_TIME 73 9558
TEST_TIME 74 9547
TEST_TIME 75 9590
TEST_TIME 76 9629
TEST_TIME 77 9616
TEST_TIME 78 9606
TEST_TIME 79 9655
TEST_TIME 80 9635
TEST_TIME 81 9695
TEST_TIME 82 9712
TEST_TIME 83 9732
TEST_TIME 84 9762
TEST_TIME 85 9822
TEST_TIME 86 9812
TEST_TIME 87 9770
TEST_TIME 88 9706
TEST_TIME 89 9702
TEST_TIME 90 9700
TEST_TIME 91 9654
TEST_TIME 92 9598
TEST_TIME 93 9543
TEST_TIME 94 9609
TEST_TIME 95 9575
TEST_TIME 96 9534
TEST_TIME 97 9565
TEST_TIME 98 9580
TEST_TIME 99 9625
TEST_TIME 100 9600
TEST_TIME 101 9625
TEST_TIME 102 9656
TEST_TIME 103 9645
TEST_TIME 104 9708
TEST_TIME 105 9731
TEST_TIME 106 9697
TEST_TIME 107 9716
TEST_TIME 108 9784
TEST_TIME 109 9735
TEST_TIME 110 9654
TEST_TIME 111 9629
TEST_TIME 112 9598
TEST_TIME 113 9581
TEST_TIME 114 9578
TEST_TIME 115 9575
TEST_TIME 116 9539
TEST_TIME 117 9498
TEST_TIME 118 9514
TEST_TIME 119 9503
TEST_TIME 120 9465

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-09-2021 22:39:27 to 24-09-2021 22:39:30.

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 1566907 1566907 0 0.00 0 21976 6 0 0
doc_list_biud 1566907 1566907 0 0.00 0 52140 29 0 0
sp_add_invoice_to_stock 5071 4973 98 1.93 38 181959 18782 0 98
sp_cancel_adding_invoice 5814 5814 0 0.00 3 80927 784 0 0
sp_cancel_client_order 1735 1735 0 0.00 4 15111 367 0 0
sp_cancel_customer_reserve 583604 583604 0 0.00 1 52141 61 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 2 4270 82 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 5514 101 0 0
sp_cancel_supplier_invoice 6581 6572 9 0.13 1 14444 849 0 9
sp_cancel_supplier_order 434 434 0 0.00 8 1284743 105060 0 0
sp_cancel_write_off 8840 8840 0 0.00 1 11867 126 0 0
sp_client_order 20148 20148 0 0.00 4 49147 368 0 0
sp_customer_reserve 467299 467299 0 0.00 0 40580 183 0 0
sp_fill_shopping_cart 31142 31142 0 0.00 1 12187 39 0 0
sp_fill_shopping_cart_clo_res 467296 467296 0 0.00 0 15294 14 0 0
sp_get_clo_for_invoice 4970 4970 0 0.00 0 28458 308 0 0
sp_kill_cost_storno 15774 15774 0 0.00 0 8289 61 0 0
sp_kill_qstorno_ret_qs2qd 1183054 1183054 0 0.00 0 52137 33 0 0
sp_kill_qty_storno 622002 622002 0 0.00 0 52139 72 0 0
sp_lock_selected_doc 20755 20755 0 0.00 0 6824 5 0 0
sp_make_cost_storno 27738 27738 0 0.00 0 21973 61 0 0
sp_multiply_rows_for_pdistr 27738 27738 0 0.00 0 6035 28 0 0
sp_multiply_rows_for_qdistr 20148 20148 0 0.00 0 49138 171 0 0
sp_pay_from_customer 7006 7006 0 0.00 4 12422 114 0 0
sp_pay_to_supplier 5729 5729 0 0.00 5 14438 131 0 0
sp_payment_common 12735 12735 0 0.00 1 14423 36 0 0
sp_qd_handle_on_cancel_clo 1735 1735 0 0.00 0 15103 336 0 0
sp_qd_handle_on_invoice_upd_sts 10778 10778 0 0.00 0 40398 353 0 0
sp_qd_handle_on_reserve_upd_sts 10039 10039 0 0.00 0 20409 55 0 0
sp_reserve_write_off 10039 10039 0 0.00 6 21985 188 0 0
sp_supplier_invoice 5463 5463 0 0.00 6 89449 3388 0 0
sp_supplier_order 5545 5545 0 0.00 5 32189 1232 0 0
srv_aggregate_perf_data 99 99 0 0.00 2873 218572 23053 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1602 114990 42998 0 0
srv_find_qd_qs_mism 1087535 1087535 0 0.00 0 11234 5 0 0
srv_make_invnt_saldo 589 589 0 0.00 36 116047 1235 0 0
srv_make_money_saldo 808 808 0 0.00 1 3793 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 197459 512034 353536 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4973 4973 0 0.00 1 24843 185 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 4511 111 0 0
t$perf-norm:sp_cancel_client_order 1735 1735 0 0.00 0 4213 12 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 2379 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 1604 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 4132 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1172 15 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 21163 1556 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 6302 19 0 0
t$perf-norm:sp_client_order 20148 20148 0 0.00 0 5744 18 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 503 3 0 0
t$perf-norm:sp_pay_from_customer 7006 7006 0 0.00 0 6756 10 0 0
t$perf-norm:sp_pay_to_supplier 5729 5729 0 0.00 0 7972 12 0 0
t$perf-norm:sp_reserve_write_off 10039 10039 0 0.00 0 5024 8 0 0
t$perf-norm:sp_supplier_invoice 5463 5463 0 0.00 0 10492 20 0 0
t$perf-norm:sp_supplier_order 5545 5545 0 0.00 0 3921 15 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 276 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 72 4 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 1104 26 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1618 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 3 0 0
v_add_invoice_to_stock 10124 9928 196 1.93 0 4216 19 0 196
v_all_customers 36838 36838 0 0.00 0 26 1 0 0
v_all_suppliers 30710 30710 0 0.00 0 15 1 0 0
v_all_wares 40296 40296 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 1027 9 0 0
v_cancel_client_order 3470 3470 0 0.00 0 3426 7 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 2545 6 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 2371 10 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 2018 10 0 0
v_cancel_supplier_invoice 810 792 18 2.22 0 2091 21 0 18
v_cancel_supplier_order 868 868 0 0.00 0 596 5 0 0
v_cancel_write_off 1984 1984 0 0.00 0 1783 15 0 0
v_max_id_clo_ord 5537 5537 0 0.00 0 368 1 0 0
v_max_id_clo_res 965 965 0 0.00 0 2700 16 0 0
v_max_id_ord_sup 5457 5457 0 0.00 0 6540 5 0 0
v_max_non_paid_invoice 1368 1368 0 0.00 0 100 2 0 0
v_max_non_paid_realizn 3800 3800 0 0.00 0 3125 4 0 0
v_min_id_clo_ord 5537 5537 0 0.00 0 2745 5 0 0
v_min_id_clo_res 965 965 0 0.00 0 5283 12 0 0
v_min_id_ord_sup 5457 5457 0 0.00 0 7056 8 0 0
v_min_non_paid_invoice 5729 5729 0 0.00 0 14418 28 0 0
v_min_non_paid_realizn 7006 7006 0 0.00 0 8577 22 0 0
v_our_firm 9870 9870 0 0.00 0 13 1 0 0
v_reserve_write_off 20078 20078 0 0.00 0 3906 6 0 0
x_lock_depdocs_on_canc_invoice 11990 11990 0 0.00 0 20814 123 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 12687 407 0 0
x_make_qstorno_1000_1200 5545 5545 0 0.00 2 32182 1154 0 0
x_make_qstorno_1200_2000 5463 5463 0 0.00 3 81154 3251 0 0
x_make_qstorno_2100_3300 467299 467299 0 0.00 0 40579 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-09-2021 22:39:31 to 24-09-2021 22:39:35.

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
20:07:59 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:09:01 35297 8192000000 10384885280 10708475904 1437381088 14.927 100 89 178 1 1471943984 6323536 28239360
20:10:39 27275 8192000000 10458621360 10792783872 1508318080 15.549 100 90 181 0 1545898304 9126336 28453888
20:12:13 23000 8192000000 10513736832 10855612416 1560348464 15.999 100 84 168 0 1596217376 10182848 25686064
20:13:38 14480 8192000000 10580899808 10938200064 1640900656 16.687 100 83 167 0 1676966720 11038544 25027520
20:14:57 5573 8192000000 10655879472 11025170432 1716402832 17.322 100 87 173 1 1754512928 13599088 24511008
20:16:07 5158 8192000000 10710709600 11089928192 1771919296 17.783 100 78 157 0 1806219040 12309728 21990016
20:17:14 2427 8192000000 10765319152 11151093760 1826061344 18.227 100 88 176 2 1863634352 12659712 24913296
20:18:18 1762 8192000000 10814477456 11201949696 1871162704 18.594 100 92 184 0 1911404912 14715936 25526272
20:19:22 46950 8192000000 10818864784 11221405696 1878943792 18.657 100 53 106 0 1908689904 14990128 14755984
20:21:09 9480 8192000000 10805972880 11229523968 1888434912 18.733 100 22 44 2 1901511440 6530992 6545536
20:22:19 6484 8192000000 10818443152 11248119808 1902628384 18.847 100 29 58 1 1919160912 8832112 7700416
20:23:29 4562 8192000000 10839215456 11253899264 1913771104 18.937 100 42 87 1 1932242768 5667120 12804544
20:24:34 9128 8192000000 10861416352 11265294336 1921208384 18.997 100 68 134 1 1946904512 6241904 19454224
20:25:44 6285 8192000000 10837000320 11267825664 1921683920 19.000 100 33 66 3 1936948912 5843152 9421840
20:26:53 7574 8192000000 10817613952 11253043200 1884394544 18.701 98 16 32 0 1894975296 5963472 4617280
20:28:04 59009 8192000000 10778695264 11205722112 1808459472 18.083 95 27 55 1 1825889296 9719344 7710480
20:30:09 5101 8192000000 10717423520 11133796352 1761866368 17.700 97 25 50 0 1774954320 6019152 7068800
20:31:15 6323 8192000000 10746665712 11168935936 1834754720 18.298 100 26 52 0 1849480064 6782672 7942672
20:32:24 61148 8192000000 10687906416 11100434432 1765269328 17.728 97 21 42 1 1776217136 4841344 6106464
20:34:26 7305 8192000000 10745024144 11170734080 1833885792 18.291 100 33 64 3 1848975600 5770208 9319600
20:35:34 11979 8192000000 10778717568 11202592768 1858553456 18.492 100 28 56 1 1872461888 5648608 8259824
20:36:46 18800 8192000000 10789245360 11217989632 1869984976 18.584 100 15 30 0 1881032784 5864304 5183504
20:38:07 68989 8192000000 10836977456 11257667584 1902224304 18.844 100 40 78 1 1919282640 6620720 10437616
20:40:27 26017 8192000000 10874413520 11297517568 1934785696 19.105 100 53 102 1 1961380848 11804368 14790784
20:41:56 29165 8192000000 10892294160 11327684608 1958268480 19.292 100 50 99 3 1985823472 12322256 15232736
20:44:03 9263 8192000000 10919915040 11361759232 1979794880 19.463 100 46 92 2 2018344032 24079712 14469440
20:45:16 10242 8192000000 10941314208 11378954240 1994792288 19.582 100 59 118 0 2039911056 27704576 17414192
20:46:28 2608 8192000000 10969690128 11395719168 2011009120 19.709 100 68 133 3 2059934000 29297072 19627808
20:47:34 2465 8192000000 10990557744 11419996160 2030983616 19.866 100 69 138 1 2080261360 28709136 20568608
20:48:39 9864 8192000000 10988838976 11419582464 2026587632 19.832 100 65 129 0 2076619696 30626832 19405232
20:49:55 4024 8192000000 11006239056 11440934912 2040277616 19.939 100 65 127 0 2097002112 37607552 19116944
20:51:04 6746 8192000000 11001004592 11425214464 2016027792 19.749 99 68 136 1 2074961840 37956800 20977248
20:52:18 3195 8192000000 11028969312 11453030400 2043378784 19.963 100 87 175 1 2114648096 43793152 27476160
20:53:28 1593 8192000000 11015088144 11435417600 2031895088 19.873 99 88 175 3 2103750608 44452640 27402880
20:54:39 2406 8192000000 11022129904 11443298304 2030097712 19.859 100 80 161 1 2103180800 48142784 24940304
20:55:46 5961 8192000000 10995721744 11430924288 2003487408 19.650 100 70 139 1 2082468016 56785248 22195360
20:57:02 2537 8192000000 10981846800 11400458240 1978624992 19.454 100 84 169 1 2060325872 55310944 26389936
20:58:09 15905 8192000000 10953448480 11399557120 1945756208 19.193 98 44 88 2 2017651824 56837792 15057824
20:59:37 7013 8192000000 10960795312 11402829824 1964541616 19.342 100 42 84 2 2048022224 68688048 14792560
21:01:01 14971 8192000000 10932464064 11386261504 1964377504 19.341 100 43 86 0 2022438608 43545152 14515952
21:02:16 40608 8192000000 10913740384 11380334592 1962629680 19.327 100 16 32 2 2001501648 33246688 5625280
21:03:58 49883 8192000000 10866622000 11332222976 1875810640 18.631 96 14 28 1 1918435936 37549824 5075472
21:05:54 39674 8192000000 10822999760 11275280384 1846851232 18.397 96 52 105 2 1868774128 7639856 14283040
21:08:02 98813 8192000000 10888434992 11328905216 1942441424 19.166 100 44 88 2 1961944224 7831792 11671008
21:10:44 45061 8192000000 10873656896 11320684544 1885386560 18.709 97 19 38 0 1898703040 8328448 4988032
21:12:31 60903 8192000000 10867534128 11323146240 1911444016 18.918 98 17 34 1 1925178368 9040592 4693760
21:14:33 27765 8192000000 10898010368 11342487552 1950430288 19.230 100 38 76 1 1977386576 15824736 11131552
21:16:02 30671 8192000000 10911292208 11365928960 1966313392 19.356 99 53 106 1 1998865504 16862720 15689392
21:17:36 45906 8192000000 10901922432 11393392640 1978957472 19.456 100 14 28 0 2007519840 23423504 5138864
21:19:23 56347 8192000000 10912146256 11390414848 1984341344 19.499 100 22 44 1 2010937776 20241840 6354592
21:21:20 46701 8192000000 10925135856 11392425984 1991205536 19.553 100 17 34 0 2007924272 12048864 4669872
21:23:10 48847 8192000000 10917643008 11394379776 1995208912 19.585 100 12 24 1 2012454944 14037264 3208768
21:25:15 51148 8192000000 10918869504 11383664640 1961828144 19.321 98 35 70 0 1987187632 15163008 10196480
21:27:07 43770 8192000000 10935054864 11406614528 1987234384 19.522 99 24 48 1 2010882960 17029184 6619392
21:29:07 62628 8192000000 10945824864 11405717504 1988770896 19.534 99 23 44 1 2006805424 12060496 5974032
21:31:16 50430 8192000000 10925071664 11402448896 1982646752 19.486 99 16 32 0 2001931232 14376096 4908384
21:33:07 34423 8192000000 10933832016 11399315456 1991315728 19.554 100 30 60 1 2012818528 12777920 8724880
21:34:48 41161 8192000000 10918018976 11392086016 1950720384 19.232 98 28 55 1 1972581824 14063328 7798112
21:36:44 82768 8192000000 10887156112 11362250752 1926070048 19.035 97 21 42 0 1943736336 11966608 5699680
21:39:38 63495 8192000000 10890866128 11359535104 1884836128 18.704 94 28 56 2 1905705184 12967328 7901728
21:41:43 28860 8192000000 10911731360 11359645696 1957311616 19.285 99 33 59 1 1978214864 12684496 8218752
21:43:16 62792 8192000000 10857725776 11327475712 1870125424 18.585 95 13 26 2 1888160080 14461632 3573024
21:45:21 44468 8192000000 10874797760 11342061568 1931608752 19.080 99 9 18 1 1943258720 9267296 2382672
21:47:05 77161 8192000000 10873404480 11347685376 1906705264 18.880 97 20 40 0 1926585888 13573744 6306880
21:49:30 49006 8192000000 10869646608 11343765504 1901007792 18.834 98 21 43 3 1932499040 24860848 6630400
21:51:25 48696 8192000000 10863276224 11339751424 1927545408 19.047 99 10 20 0 1951871904 21364704 2961792
21:53:14 44713 8192000000 10861292064 11315879936 1865282496 18.546 96 17 32 1 1879866832 9502064 5082272
21:55:01 26784 8192000000 10864457632 11318005760 1911829760 18.921 99 36 72 0 1932904640 10968048 10106832
21:56:35 38218 8192000000 10861381696 11313180672 1883979616 18.697 97 34 67 0 1904316848 10170992 10166240
21:58:14 60870 8192000000 10852516000 11333513216 1886422784 18.717 98 10 20 0 1901841632 12265904 3152944
22:00:18 50573 8192000000 10866442000 11322314752 1923922896 19.018 100 25 50 0 1940332352 9059472 7349984
22:02:11 43525 8192000000 10864475280 11333726208 1921680512 19.000 100 10 20 1 1935779776 11297632 2801632
22:04:28 58317 8192000000 10851084848 11309637632 1889236944 18.740 97 31 62 1 1910323328 12489312 8597072
22:06:26 75158 8192000000 10867980240 11337203712 1919047088 18.979 99 16 32 0 1939436288 15821088 4568112
22:08:42 46399 8192000000 10857797680 11335053312 1946838496 19.201 100 8 16 0 1961186368 12245680 2102192
22:10:31 59387 8192000000 10887632384 11360739328 1970148880 19.387 100 15 30 0 1983121776 9186592 3786304
22:12:35 42885 8192000000 10893193600 11357384704 1931240416 19.077 98 13 26 2 1942038912 6664080 4134416
22:14:25 64938 8192000000 10889503056 11367092224 1959284288 19.300 99 22 44 1 1975737232 10230944 6222000
22:16:35 61900 8192000000 10912731568 11388129280 1970040752 19.386 99 17 34 0 1984913232 10047216 4825264
22:18:44 24998 8192000000 10912202704 11379933184 1958385888 19.293 98 43 86 2 1982541760 11337568 12818304
22:20:11 78197 8192000000 10925506496 11398246400 1991584880 19.556 100 15 30 0 2008631888 13007088 4039920
22:22:39 112852 8192000000 10938713200 11406159872 1964536448 19.342 98 23 46 0 1980824304 9675296 6612560
22:25:32 114989 8192000000 10915884896 11384864768 1938319616 19.133 97 14 28 1 1953627488 11767968 3539904
22:28:27 73500 8192000000 10908325504 11361619968 1886078560 18.714 94 31 62 0 1902245936 8078160 8089216
22:30:46 60794 8192000000 10873297200 11353477120 1920047600 18.987 98 10 20 1 1939374576 16496448 2830528
22:32:48 47273 8192000000 10854395408 11325698048 1884208160 18.699 96 3 6 1 1897310144 11555056 1546928
22:34:41 59586 8192000000 10873013888 11346481152 1930415728 19.070 98 25 50 1 1951582912 14822528 6344656
22:36:43 37028 8192000000 10855271296 11315978240 1867741392 18.566 96 22 44 0 1883798432 9724768 6332272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-09-2021 22:39:37 to 24-09-2021 22:39:52.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-09-2021 22:39:53 to 24-09-2021 22:39:55.

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          234016
MON$OLDEST_ACTIVE               236415
MON$OLDEST_SNAPSHOT             236415
MON$NEXT_TRANSACTION            236415
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                       2025120
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.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
:208: 
:209: mscs-test-161.budzdorov.ru    Fri Sep 24 22:37:56 2021
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:211: 
:212: 
:213: mscs-test-161.budzdorov.ru    Fri Sep 24 22:37:56 2021
:214:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:215: 
:216: 
:217: mscs-test-161.budzdorov.ru    Fri Sep 24 22:38:03 2021
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:219: 
:220: 
:221: mscs-test-161.budzdorov.ru    Fri Sep 24 22:38:04 2021
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:223: 
--- end of diff output ---

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

24.09.21 22:39:56. 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-24 22:39:58 No severe PSQL-related problems occured
24.09.21 22:40:00. End of report.