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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16167788 kB
MemAvailable 30684072 kB
Buffers 0 kB
Cached 15518300 kB
SwapCached 3816 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-08 16:37:55
DTS_END                         2021-10-08 16:37:55.1600
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/58588
TRN_ID                          237898
ATT_ID                          676
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: 8794.83 19962 1055380
customer order: creation 166.64 344 19997
customer order: refuse 14.18 346 1701
order to supplier: creation 43.72 1224 5246
order to supplier: removal 3.31 98470 397
invoice (draft): creation 42.98 3372 5158
invoice (draft): removal 50.83 760 6100
invoice accept: apply 40.04 16920 4805
invoice accept: cancel 46.30 745 5556
customer reserve: creation 3630.11 171 435613
customer reserve: removal 4473.59 57 536831
realization accept: apply 79.31 175 9517
realization accept: cancel 79.70 114 9564
payment from customer: creation 56.63 140 6796
payment from customer: removal 5.31 82 637
payment to supplier: creation 46.42 137 5570
payment to supplier: removal 3.56 93 427
service: total inventory turnovers 4.56 1121 547
service: total monetary turnovers 6.69 17 803
service: aggregate perf. data 0.93 16504 111
service: refresh index statistics 0.03 258450 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-10-2021 16:39:00 to 08-10-2021 16:39:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2291
TEST_TIME 2 2270
TEST_TIME 3 2707
TEST_TIME 4 2798
TEST_TIME 5 3043
TEST_TIME 6 3041
TEST_TIME 7 3155
TEST_TIME 8 3347
TEST_TIME 9 3825
TEST_TIME 10 3968
TEST_TIME 11 4198
TEST_TIME 12 4312
TEST_TIME 13 4569
TEST_TIME 14 4913
TEST_TIME 15 5825
TEST_TIME 16 6065
TEST_TIME 17 6568
TEST_TIME 18 7135
TEST_TIME 19 7249
TEST_TIME 20 7498
TEST_TIME 21 8116
TEST_TIME 22 8891
TEST_TIME 23 9147
TEST_TIME 24 9707
TEST_TIME 25 9621
TEST_TIME 26 9678
TEST_TIME 27 9776
TEST_TIME 28 9782
TEST_TIME 29 9629
TEST_TIME 30 9592
TEST_TIME 31 9490
TEST_TIME 32 9452
TEST_TIME 33 9377
TEST_TIME 34 9277
TEST_TIME 35 9151
TEST_TIME 36 9172
TEST_TIME 37 9039
TEST_TIME 38 9001
TEST_TIME 39 8856
TEST_TIME 40 8977
TEST_TIME 41 8890
TEST_TIME 42 8875
TEST_TIME 43 8864
TEST_TIME 44 8830
TEST_TIME 45 8838
TEST_TIME 46 8753
TEST_TIME 47 8739
TEST_TIME 48 8670
TEST_TIME 49 8774
TEST_TIME 50 8934
TEST_TIME 51 8888
TEST_TIME 52 8898
TEST_TIME 53 8900
TEST_TIME 54 8877
TEST_TIME 55 8924
TEST_TIME 56 8996
TEST_TIME 57 9041
TEST_TIME 58 9052
TEST_TIME 59 9123
TEST_TIME 60 9060
TEST_TIME 61 9158
TEST_TIME 62 9109
TEST_TIME 63 9063
TEST_TIME 64 8994
TEST_TIME 65 8957
TEST_TIME 66 8941
TEST_TIME 67 8854
TEST_TIME 68 8811
TEST_TIME 69 8809
TEST_TIME 70 8754
TEST_TIME 71 8714
TEST_TIME 72 8683
TEST_TIME 73 8638
TEST_TIME 74 8652
TEST_TIME 75 8820
TEST_TIME 76 8810
TEST_TIME 77 8890
TEST_TIME 78 8932
TEST_TIME 79 8901
TEST_TIME 80 8954
TEST_TIME 81 8932
TEST_TIME 82 9029
TEST_TIME 83 9061
TEST_TIME 84 9060
TEST_TIME 85 9013
TEST_TIME 86 8942
TEST_TIME 87 8924
TEST_TIME 88 8878
TEST_TIME 89 8846
TEST_TIME 90 8830
TEST_TIME 91 8797
TEST_TIME 92 8753
TEST_TIME 93 8763
TEST_TIME 94 8766
TEST_TIME 95 8844
TEST_TIME 96 8839
TEST_TIME 97 8830
TEST_TIME 98 8869
TEST_TIME 99 8871
TEST_TIME 100 8918
TEST_TIME 101 8891
TEST_TIME 102 8885
TEST_TIME 103 9010
TEST_TIME 104 9011
TEST_TIME 105 9014
TEST_TIME 106 9022
TEST_TIME 107 9054
TEST_TIME 108 8999
TEST_TIME 109 8951
TEST_TIME 110 8908
TEST_TIME 111 8865
TEST_TIME 112 8903
TEST_TIME 113 8878
TEST_TIME 114 8848
TEST_TIME 115 8807
TEST_TIME 116 8760
TEST_TIME 117 8791
TEST_TIME 118 8784
TEST_TIME 119 8802
TEST_TIME 120 8797

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1459745 1459745 0 0.00 0 34937 6 0 0
doc_list_biud 1459745 1459745 0 0.00 0 21237 27 0 0
sp_add_invoice_to_stock 4957 4805 152 3.06 33 213069 16920 0 152
sp_cancel_adding_invoice 5556 5556 0 0.00 5 90441 746 0 0
sp_cancel_client_order 1701 1701 0 0.00 4 9625 346 0 0
sp_cancel_customer_reserve 536831 536831 0 0.00 1 18672 58 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 2 8367 82 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 10777 94 0 0
sp_cancel_supplier_invoice 6113 6100 13 0.21 2 12333 760 0 13
sp_cancel_supplier_order 397 397 0 0.00 12 1067501 98471 0 0
sp_cancel_write_off 9564 9564 0 0.00 1 12675 115 0 0
sp_client_order 19997 19997 0 0.00 3 33805 344 0 0
sp_customer_reserve 435613 435613 0 0.00 0 52098 171 0 0
sp_fill_shopping_cart 30391 30391 0 0.00 1 19819 42 0 0
sp_fill_shopping_cart_clo_res 435610 435610 0 0.00 0 20482 15 0 0
sp_get_clo_for_invoice 4802 4802 0 0.00 0 24462 350 0 0
sp_kill_cost_storno 16184 16184 0 0.00 0 13912 55 0 0
sp_kill_qstorno_ret_qs2qd 1089723 1089723 0 0.00 0 18613 31 0 0
sp_kill_qty_storno 574468 574468 0 0.00 0 21235 67 0 0
sp_lock_selected_doc 19806 19806 0 0.00 0 9156 6 0 0
sp_make_cost_storno 26685 26685 0 0.00 0 24972 66 0 0
sp_multiply_rows_for_pdistr 26685 26685 0 0.00 0 15409 32 0 0
sp_multiply_rows_for_qdistr 19997 19997 0 0.00 0 12854 164 0 0
sp_pay_from_customer 6796 6796 0 0.00 4 19191 140 0 0
sp_pay_to_supplier 5570 5570 0 0.00 5 18194 138 0 0
sp_payment_common 12366 12366 0 0.00 1 12992 43 0 0
sp_qd_handle_on_cancel_clo 1701 1701 0 0.00 0 9616 318 0 0
sp_qd_handle_on_invoice_upd_sts 10358 10358 0 0.00 0 21234 304 0 0
sp_qd_handle_on_reserve_upd_sts 9517 9517 0 0.00 0 9162 51 0 0
sp_reserve_write_off 9517 9517 0 0.00 5 18349 176 0 0
sp_supplier_invoice 5158 5158 0 0.00 6 90615 3372 0 0
sp_supplier_order 5246 5246 0 0.00 6 52380 1224 0 0
srv_aggregate_perf_data 111 111 0 0.00 2693 152154 16505 0 0
srv_fill_mon_cache_memory 58 58 0 0.00 3620 165245 55061 0 0
srv_find_qd_qs_mism 1010397 1010397 0 0.00 0 14642 5 0 0
srv_make_invnt_saldo 547 547 0 0.00 38 83637 1122 0 0
srv_make_money_saldo 803 803 0 0.00 1 577 18 0 0
srv_recalc_idx_stat 4 4 0 0.00 89862 336041 258450 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4805 4805 0 0.00 0 25785 169 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 8454 112 0 0
t$perf-norm:sp_cancel_client_order 1701 1701 0 0.00 0 4523 19 0 0
t$perf-norm:sp_cancel_customer_reserve 624 624 0 0.00 0 15246 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 2211 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 232 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 16144 62 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 28355 1538 0 0
t$perf-norm:sp_cancel_write_off 922 922 0 0.00 0 678 4 0 0
t$perf-norm:sp_client_order 19997 19997 0 0.00 0 20342 19 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 3910 7 0 0
t$perf-norm:sp_pay_from_customer 6796 6796 0 0.00 0 9774 14 0 0
t$perf-norm:sp_pay_to_supplier 5570 5570 0 0.00 0 20337 22 0 0
t$perf-norm:sp_reserve_write_off 9517 9517 0 0.00 0 15372 13 0 0
t$perf-norm:sp_supplier_invoice 5158 5158 0 0.00 0 6808 18 0 0
t$perf-norm:sp_supplier_order 5246 5246 0 0.00 0 14694 18 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 134 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 58 58 0 0.00 0 4038 78 0 0
t$perf-norm:srv_make_invnt_saldo 547 547 0 0.00 0 1186 15 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 2664 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 4 0 0
v_add_invoice_to_stock 9906 9602 304 3.06 0 14759 21 0 304
v_all_customers 36300 36300 0 0.00 0 126 0 0 0
v_all_suppliers 28400 28400 0 0.00 0 62 1 0 0
v_all_wares 39994 39994 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1471 8 0 0
v_cancel_client_order 3402 3402 0 0.00 0 2344 4 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 1583 7 0 0
v_cancel_customer_reserve 1248 1248 0 0.00 0 288 2 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 3068 6 0 0
v_cancel_supplier_invoice 768 742 26 3.38 0 1185 13 0 26
v_cancel_supplier_order 794 794 0 0.00 0 1857 6 0 0
v_cancel_write_off 1844 1844 0 0.00 0 6980 21 0 0
v_max_id_clo_ord 5245 5245 0 0.00 0 5057 2 0 0
v_max_id_clo_res 936 936 0 0.00 0 3380 14 0 0
v_max_id_ord_sup 5149 5149 0 0.00 0 7807 7 0 0
v_max_non_paid_invoice 1764 1764 0 0.00 0 6569 9 0 0
v_max_non_paid_realizn 3607 3607 0 0.00 0 3576 5 0 0
v_min_id_clo_ord 5245 5245 0 0.00 0 2111 5 0 0
v_min_id_clo_res 936 936 0 0.00 0 2481 7 0 0
v_min_id_ord_sup 5149 5149 0 0.00 0 8256 8 0 0
v_min_non_paid_invoice 5570 5570 0 0.00 0 12988 33 0 0
v_min_non_paid_realizn 6796 6796 0 0.00 0 12403 32 0 0
v_our_firm 10072 10072 0 0.00 0 8 0 0 0
v_reserve_write_off 19034 19034 0 0.00 0 4423 5 0 0
x_lock_depdocs_on_canc_invoice 11285 11285 0 0.00 0 11770 110 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 3180 338 0 0
x_make_qstorno_1000_1200 5246 5246 0 0.00 1 52371 1153 0 0
x_make_qstorno_1200_2000 5158 5158 0 0.00 2 84865 3202 0 0
x_make_qstorno_2100_3300 435613 435613 0 0.00 0 52097 154 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:07:58 23 8192000000 8809861200 8824725504 14214256 0.173 4 3 5 0 14408736 77360 117120
14:08:59 32074 8192000000 9925548416 10243526656 1001758624 10.896 100 89 178 1 1031209472 6482672 22968176
14:10:41 22861 8192000000 9971852928 10299338752 1056082448 11.419 100 92 184 1 1088435072 9201648 23150976
14:12:09 15285 8192000000 9999463200 10339483648 1085827568 11.703 100 85 168 1 1117120528 10713904 20579056
14:13:27 13766 8192000000 10039060512 10393395200 1130062352 12.122 100 75 149 3 1158029088 10634928 17331808
14:14:44 6339 8192000000 10100538544 10465222656 1188222304 12.667 100 78 157 0 1217875136 11840400 17812432
14:15:59 4851 8192000000 10140689504 10515550208 1236319392 13.112 100 74 146 1 1264263328 12185696 15758240
14:17:06 1756 8192000000 10178908640 10555461632 1259579984 13.326 100 90 180 1 1294149120 13452768 21116368
14:18:10 3107 8192000000 10198993936 10583306240 1282997584 13.540 100 85 171 0 1315412256 13206704 19207968
14:19:15 8907 8192000000 10208931376 10603380736 1301246816 13.707 100 61 122 0 1325465856 10726656 13492384
14:20:26 4966 8192000000 10201487376 10608549888 1315477120 13.836 100 32 64 0 1328672624 5565296 7630208
14:21:33 7484 8192000000 10206898448 10626056192 1324927280 13.921 100 24 48 1 1336549392 6081536 5540576
14:22:41 10869 8192000000 10223701072 10639122432 1334521168 14.008 100 29 58 0 1347756720 6917552 6318000
14:23:52 52271 8192000000 10238227424 10656202752 1340547488 14.062 100 26 50 0 1352331568 5896800 5887280
14:25:45 7492 8192000000 10231415776 10653507584 1331746512 13.983 99 30 60 4 1344861312 6466544 6648256
14:26:55 13420 8192000000 10205604192 10626801664 1290145776 13.606 97 28 56 1 1302560880 5385808 7029296
14:28:12 17230 8192000000 10180745760 10589511680 1225911920 13.016 93 25 50 0 1236556528 5016912 5627696
14:29:30 18723 8192000000 10154857680 10564800512 1214123376 12.907 96 20 40 0 1228111136 9829760 4158000
14:30:50 3920 8192000000 10155495920 10557284352 1251835568 13.255 99 24 46 3 1267493536 9724224 5933744
14:31:57 6056 8192000000 10171948976 10576072704 1274390368 13.462 100 24 49 2 1285785712 4786944 6608400
14:33:05 64790 8192000000 10175028912 10588295168 1273568832 13.454 99 12 24 0 1281507216 4839280 3099104
14:35:11 6207 8192000000 10186198928 10596868096 1290984592 13.613 99 31 62 1 1304820928 6173072 7663264
14:36:19 12990 8192000000 10207286656 10623090688 1312656944 13.810 100 14 28 0 1321209040 5041984 3510112
14:37:36 13666 8192000000 10225119904 10647171072 1336344112 14.024 100 9 18 1 1342464064 4605936 1514016
14:38:54 61668 8192000000 10262524096 10681569280 1355219472 14.194 100 52 104 2 1377846880 9792192 12835216
14:41:30 25882 8192000000 10303130032 10723426304 1385824208 14.469 100 41 82 4 1414348960 18587136 9937616
14:43:02 9614 8192000000 10311918928 10735214592 1392872736 14.531 100 45 90 1 1426581184 23136320 10572128
14:44:17 28954 8192000000 10324456480 10751315968 1398071376 14.578 100 39 78 2 1437437968 29465232 9901360
14:45:59 11892 8192000000 10347847648 10764505088 1392712720 14.530 98 56 112 2 1441815632 34956592 14146320
14:47:21 12421 8192000000 10354808720 10780569600 1417900048 14.754 100 63 126 4 1471490624 36764608 16825968
14:48:40 5443 8192000000 10358521824 10775347200 1411158992 14.694 100 63 123 2 1467625120 40200080 16266048
14:49:49 7951 8192000000 10364886992 10780733440 1407983696 14.666 100 67 134 0 1472304288 46847872 17472720
14:51:05 3618 8192000000 10358469920 10763087872 1386343024 14.473 99 80 159 0 1455353424 49366208 19644192
14:52:14 8929 8192000000 10356760672 10776514560 1385688224 14.467 99 62 124 0 1458024992 56350192 15986576
14:53:28 22519 8192000000 10345378272 10769952768 1373638256 14.360 98 51 102 1 1436169056 49433200 13097600
14:55:01 17057 8192000000 10337932368 10757812224 1369145088 14.319 99 51 102 0 1429989168 47575504 13268576
14:56:25 17721 8192000000 10330470944 10768277504 1381798912 14.433 99 26 52 1 1434571520 45402832 7369776
14:57:45 17805 8192000000 10313583664 10750824448 1345662176 14.108 98 30 60 2 1409671680 55417408 8592096
14:59:19 55087 8192000000 10288104496 10731143168 1349598816 14.144 99 17 34 1 1393943456 39475776 4868864
15:01:15 28843 8192000000 10270266208 10708652032 1315119632 13.832 97 24 49 2 1342082992 21172320 5791040
15:02:52 37111 8192000000 10285781776 10724216832 1368381504 14.313 100 25 49 0 1394175696 20039248 5754944
15:04:32 23538 8192000000 10256758864 10701127680 1323047136 13.904 97 13 26 0 1348012256 21538528 3426592
15:05:59 66975 8192000000 10251014544 10691448832 1332658208 13.991 99 13 25 0 1355863008 20309072 2895728
15:08:08 115203 8192000000 10242772432 10685022208 1322610688 13.900 97 19 38 0 1335054000 8411424 4031888
15:11:08 71757 8192000000 10273147712 10722381824 1358130752 14.221 99 11 22 0 1373962960 13747696 2084512
15:13:21 78771 8192000000 10291447696 10735857664 1377798720 14.397 99 30 60 1 1395024000 10751456 6473824
15:15:40 70204 8192000000 10277910976 10723336192 1326261504 13.933 96 18 36 1 1342871856 12260400 4349952
15:18:03 38215 8192000000 10279345504 10742116352 1363535728 14.269 99 16 32 2 1383209504 15969968 3703808
15:19:52 71718 8192000000 10288186160 10744573952 1387160576 14.481 100 11 22 1 1405234336 15165088 2908672
15:22:08 63097 8192000000 10310379840 10754588672 1394368336 14.545 100 30 58 2 1420615616 18926976 7320304
15:24:15 68540 8192000000 10304950688 10767269888 1384831424 14.460 99 18 36 1 1411488320 22331552 4325344
15:26:26 54270 8192000000 10295874064 10779377664 1362082704 14.256 98 9 18 0 1394126256 29965568 2077984
15:28:37 19064 8192000000 10302222672 10745204736 1379284096 14.410 99 36 72 1 1396199968 9315200 7600672
15:30:13 20033 8192000000 10325760768 10762854400 1397038800 14.569 100 55 110 2 1422943856 13919520 11985536
15:31:35 18962 8192000000 10316199376 10751647744 1383782112 14.450 99 54 108 0 1407186224 11850288 11553824
15:32:54 116927 8192000000 10294040640 10750910464 1310635184 13.792 94 26 52 0 1331121408 14865136 5621088
15:35:51 86223 8192000000 10279771104 10732605440 1296201248 13.661 94 15 30 0 1312789968 13397232 3191488
15:38:17 53866 8192000000 10266024784 10713464832 1317678192 13.856 96 12 24 1 1331964272 11830320 2455760
15:40:11 70456 8192000000 10265577744 10716393472 1357253664 14.213 99 22 44 0 1370313920 7363840 5696416
15:42:26 65333 8192000000 10258661440 10709934080 1319141712 13.869 96 18 36 1 1336604896 13549056 3914128
15:44:55 76939 8192000000 10264065952 10724872192 1347614848 14.126 98 22 42 1 1363659792 10733920 5311024
15:47:34 61943 8192000000 10258013216 10713645056 1353447792 14.178 99 16 32 0 1364156480 7279712 3428976
15:49:37 48543 8192000000 10297113520 10751463424 1388605888 14.493 100 19 37 0 1407120192 13679312 4834992
15:51:32 84481 8192000000 10333816128 10795110400 1391886928 14.523 100 20 41 1 1426734336 29530576 5316832
15:55:22 83509 8192000000 10294133120 10751782912 1367623552 14.306 98 16 32 0 1383726320 12126736 3976032
15:57:48 52616 8192000000 10292690256 10734964736 1320248848 13.879 95 24 48 0 1340590720 15510848 4831024
15:59:42 156658 8192000000 10286551648 10735157248 1325318800 13.925 96 11 22 0 1343987568 16561776 2106992
16:03:19 70723 8192000000 10292871184 10738864128 1381319104 14.428 100 42 84 1 1400856272 10040064 9497104
16:05:40 45456 8192000000 10285473616 10743193600 1380129888 14.418 99 24 48 0 1393249728 7965728 5154112
16:07:38 39911 8192000000 10293205344 10755563520 1390000000 14.506 100 10 21 0 1406613104 14476368 2136736
16:09:20 54568 8192000000 10274021408 10740871168 1381309952 14.428 100 16 32 1 1392685344 7861056 3514336
16:11:23 85931 8192000000 10315701520 10780983296 1403781408 14.629 100 18 36 0 1419908704 12092096 4035200
16:13:50 57884 8192000000 10304824496 10762317824 1393693552 14.539 99 27 54 0 1411543296 11390080 6459664
16:15:49 44486 8192000000 10275192512 10733506560 1343848352 14.092 96 12 24 0 1356202784 9449792 2904640
16:17:35 93732 8192000000 10311726976 10755661824 1345280128 14.105 96 26 52 0 1360975632 9884880 5810624
16:20:09 102918 8192000000 10297411648 10755301376 1311243472 13.797 94 10 20 0 1329780160 16231152 2305536
16:22:56 165245 8192000000 10290139408 10748112896 1333566704 13.999 95 10 18 1 1351186928 15551088 2069136
16:26:43 92749 8192000000 10282346752 10730815488 1334545760 14.008 96 23 46 0 1348882448 9047056 5289632
16:29:16 71148 8192000000 10258990032 10715901952 1349335520 14.141 99 13 25 1 1359960512 8089968 2535024
16:32:33 64810 8192000000 10261837536 10709688320 1344125392 14.095 98 22 44 1 1357798784 8076496 5596896
16:35:09 43171 8192000000 10266181232 10715631616 1325094736 13.923 96 33 64 1 1339851728 6689776 8067216
16:37:09 50414 8192000000 9183323536 9437888512 337676720 3.958 25 5 10 0 341567456 2967968 922768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 152 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 08-10-2021 16:39:30 to 08-10-2021 16:39:31.

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          237268
MON$OLDEST_ACTIVE               239566
MON$OLDEST_SNAPSHOT             239566
MON$NEXT_TRANSACTION            239566
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                       2007552
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.33519 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33519 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33519 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33519 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 ---
:54: 
:55: mscs-test-161.budzdorov.ru    Fri Oct  8 16:38:02 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Fri Oct  8 16:38:02 2021
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:61: 
:62: 
:63: mscs-test-161.budzdorov.ru    Fri Oct  8 16:38:03 2021
:64:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:65: 
--- end of diff output ---

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

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