Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.11.2021 16: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 17015328 kB
MemAvailable 31639320 kB
Buffers 0 kB
Cached 14696192 kB
SwapCached 0 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-11-15 16:37:55
DTS_END                         2021-11-15 16:37:55.0490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46282
TRN_ID                          228477
ATT_ID                          685
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: 9031.78 25762 1083814
customer order: creation 155.08 381 18610
customer order: refuse 13.80 352 1656
order to supplier: creation 42.73 1214 5128
order to supplier: removal 3.20 123730 384
invoice (draft): creation 42.85 3453 5142
invoice (draft): removal 53.60 855 6432
invoice accept: apply 38.78 17579 4654
invoice accept: cancel 49.18 768 5902
customer reserve: creation 3582.48 174 429897
customer reserve: removal 4769.11 66 572293
realization accept: apply 76.26 176 9151
realization accept: cancel 83.98 113 10078
payment from customer: creation 54.93 109 6591
payment from customer: removal 5.21 73 625
payment to supplier: creation 45.89 126 5507
payment to supplier: removal 3.08 109 369
service: total inventory turnovers 4.41 1282 529
service: total monetary turnovers 6.43 22 772
service: aggregate perf. data 0.75 22789 90
service: refresh index statistics 0.03 341869 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-11-2021 16:40:16 to 15-11-2021 16:40:18.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3805
TEST_TIME 2 3815
TEST_TIME 3 4086
TEST_TIME 4 4455
TEST_TIME 5 4484
TEST_TIME 6 4882
TEST_TIME 7 4709
TEST_TIME 8 4752
TEST_TIME 9 4933
TEST_TIME 10 5209
TEST_TIME 11 5373
TEST_TIME 12 5222
TEST_TIME 13 5413
TEST_TIME 14 5479
TEST_TIME 15 5824
TEST_TIME 16 5952
TEST_TIME 17 6454
TEST_TIME 18 6928
TEST_TIME 19 6843
TEST_TIME 20 7186
TEST_TIME 21 7289
TEST_TIME 22 7870
TEST_TIME 23 8408
TEST_TIME 24 8819
TEST_TIME 25 9537
TEST_TIME 26 9969
TEST_TIME 27 10659
TEST_TIME 28 10704
TEST_TIME 29 10500
TEST_TIME 30 10429
TEST_TIME 31 10157
TEST_TIME 32 9959
TEST_TIME 33 10013
TEST_TIME 34 9847
TEST_TIME 35 9801
TEST_TIME 36 9777
TEST_TIME 37 9585
TEST_TIME 38 9512
TEST_TIME 39 9445
TEST_TIME 40 9412
TEST_TIME 41 9518
TEST_TIME 42 9443
TEST_TIME 43 9450
TEST_TIME 44 9371
TEST_TIME 45 9254
TEST_TIME 46 9329
TEST_TIME 47 9229
TEST_TIME 48 9127
TEST_TIME 49 8994
TEST_TIME 50 8984
TEST_TIME 51 8895
TEST_TIME 52 8890
TEST_TIME 53 8914
TEST_TIME 54 8918
TEST_TIME 55 8962
TEST_TIME 56 8988
TEST_TIME 57 8969
TEST_TIME 58 8979
TEST_TIME 59 8985
TEST_TIME 60 9127
TEST_TIME 61 9237
TEST_TIME 62 9363
TEST_TIME 63 9324
TEST_TIME 64 9332
TEST_TIME 65 9340
TEST_TIME 66 9403
TEST_TIME 67 9343
TEST_TIME 68 9287
TEST_TIME 69 9190
TEST_TIME 70 9205
TEST_TIME 71 9226
TEST_TIME 72 9170
TEST_TIME 73 9154
TEST_TIME 74 9104
TEST_TIME 75 9117
TEST_TIME 76 9116
TEST_TIME 77 9132
TEST_TIME 78 9131
TEST_TIME 79 9165
TEST_TIME 80 9146
TEST_TIME 81 9198
TEST_TIME 82 9225
TEST_TIME 83 9246
TEST_TIME 84 9239
TEST_TIME 85 9209
TEST_TIME 86 9338
TEST_TIME 87 9296
TEST_TIME 88 9306
TEST_TIME 89 9302
TEST_TIME 90 9209
TEST_TIME 91 9211
TEST_TIME 92 9179
TEST_TIME 93 9126
TEST_TIME 94 9082
TEST_TIME 95 9091
TEST_TIME 96 9076
TEST_TIME 97 9094
TEST_TIME 98 9061
TEST_TIME 99 9075
TEST_TIME 100 9087
TEST_TIME 101 9227
TEST_TIME 102 9187
TEST_TIME 103 9206
TEST_TIME 104 9217
TEST_TIME 105 9236
TEST_TIME 106 9230
TEST_TIME 107 9299
TEST_TIME 108 9247
TEST_TIME 109 9330
TEST_TIME 110 9298
TEST_TIME 111 9232
TEST_TIME 112 9180
TEST_TIME 113 9158
TEST_TIME 114 9186
TEST_TIME 115 9130
TEST_TIME 116 9103
TEST_TIME 117 9100
TEST_TIME 118 9056
TEST_TIME 119 9091
TEST_TIME 120 9033

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-11-2021 16:40:20 to 15-11-2021 16:40:23.

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 1481172 1481172 0 0.00 0 29897 6 0 0
doc_list_biud 1481171 1481171 0 0.00 0 36149 32 0 0
sp_add_invoice_to_stock 4777 4654 123 2.57 58 170389 17579 0 123
sp_cancel_adding_invoice 5902 5902 0 0.00 5 53930 768 0 0
sp_cancel_client_order 1656 1656 0 0.00 3 27272 352 0 0
sp_cancel_customer_reserve 572293 572293 0 0.00 1 30689 67 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 3 3510 74 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 6971 109 0 0
sp_cancel_supplier_invoice 6439 6432 7 0.10 2 30877 855 0 7
sp_cancel_supplier_order 384 384 0 0.00 6 1228295 123731 0 0
sp_cancel_write_off 10078 10078 0 0.00 1 17313 113 0 0
sp_client_order 18610 18610 0 0.00 3 31088 382 0 0
sp_customer_reserve 429897 429897 0 0.00 0 46896 175 0 0
sp_fill_shopping_cart 28875 28875 0 0.00 1 22865 39 0 0
sp_fill_shopping_cart_clo_res 429886 429886 0 0.00 0 10551 15 0 0
sp_get_clo_for_invoice 4643 4643 0 0.00 0 20055 334 0 0
sp_kill_cost_storno 16974 16974 0 0.00 0 7390 53 0 0
sp_kill_qstorno_ret_qs2qd 1161480 1161480 0 0.00 0 30686 36 0 0
sp_kill_qty_storno 610539 610539 0 0.00 0 36149 77 0 0
sp_lock_selected_doc 19228 19228 0 0.00 0 6480 5 0 0
sp_make_cost_storno 25892 25892 0 0.00 0 12108 48 0 0
sp_multiply_rows_for_pdistr 25892 25892 0 0.00 0 6458 25 0 0
sp_multiply_rows_for_qdistr 18610 18610 0 0.00 0 25906 180 0 0
sp_pay_from_customer 6591 6591 0 0.00 4 22790 109 0 0
sp_pay_to_supplier 5507 5507 0 0.00 5 7380 126 0 0
sp_payment_common 12098 12098 0 0.00 2 22785 27 0 0
sp_qd_handle_on_cancel_clo 1656 1656 0 0.00 0 27265 323 0 0
sp_qd_handle_on_invoice_upd_sts 10545 10545 0 0.00 0 36149 321 0 0
sp_qd_handle_on_reserve_upd_sts 9151 9151 0 0.00 0 27089 61 0 0
sp_reserve_write_off 9151 9151 0 0.00 7 27123 177 0 0
sp_supplier_invoice 5142 5142 0 0.00 6 76649 3454 0 0
sp_supplier_order 5128 5128 0 0.00 5 33733 1214 0 0
srv_aggregate_perf_data 90 90 0 0.00 3043 520240 22789 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2060 188395 46341 0 0
srv_find_qd_qs_mism 1039313 1039313 0 0.00 0 29896 5 0 0
srv_make_invnt_saldo 529 529 0 0.00 40 137048 1282 0 0
srv_make_money_saldo 772 772 0 0.00 1 1362 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 187163 451796 341869 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4654 4654 0 0.00 0 13896 163 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 4043 78 0 0
t$perf-norm:sp_cancel_client_order 1656 1656 0 0.00 0 6096 16 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 2729 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 761 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 11392 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 1259 11 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 21197 1658 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 911 5 0 0
t$perf-norm:sp_client_order 18611 18611 0 0.00 0 18149 18 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 1046 6 0 0
t$perf-norm:sp_pay_from_customer 6591 6591 0 0.00 0 7062 11 0 0
t$perf-norm:sp_pay_to_supplier 5507 5507 0 0.00 0 5647 9 0 0
t$perf-norm:sp_reserve_write_off 9151 9151 0 0.00 0 7059 12 0 0
t$perf-norm:sp_supplier_invoice 5142 5142 0 0.00 0 8810 20 0 0
t$perf-norm:sp_supplier_order 5128 5128 0 0.00 0 4558 14 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 56 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 3846 61 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 2424 22 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 5508 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9532 9286 246 2.58 0 5987 20 0 246
v_all_customers 34580 34580 0 0.00 0 12 0 0 0
v_all_suppliers 28636 28636 0 0.00 0 17 1 0 0
v_all_wares 37220 37220 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 4355 16 0 0
v_cancel_client_order 3312 3312 0 0.00 0 5143 7 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 3497 10 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 5871 16 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 2070 7 0 0
v_cancel_supplier_invoice 800 786 14 1.75 0 1387 14 0 14
v_cancel_supplier_order 768 768 0 0.00 0 3117 15 0 0
v_cancel_write_off 1882 1882 0 0.00 0 3248 15 0 0
v_max_id_clo_ord 5128 5128 0 0.00 0 833 1 0 0
v_max_id_clo_res 954 954 0 0.00 0 8019 30 0 0
v_max_id_ord_sup 5137 5137 0 0.00 0 3103 3 0 0
v_max_non_paid_invoice 1454 1454 0 0.00 0 1091 3 0 0
v_max_non_paid_realizn 3320 3320 0 0.00 0 4750 4 0 0
v_min_id_clo_ord 5128 5128 0 0.00 0 3073 6 0 0
v_min_id_clo_res 954 954 0 0.00 0 3951 5 0 0
v_min_id_ord_sup 5137 5137 0 0.00 0 2483 5 0 0
v_min_non_paid_invoice 5507 5507 0 0.00 0 5662 21 0 0
v_min_non_paid_realizn 6591 6591 0 0.00 0 22781 21 0 0
v_our_firm 9182 9182 0 0.00 0 83 1 0 0
v_reserve_write_off 18302 18302 0 0.00 0 7272 10 0 0
x_lock_depdocs_on_canc_invoice 11941 11941 0 0.00 0 26735 114 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 10444 414 0 0
x_make_qstorno_1000_1200 5128 5128 0 0.00 1 33711 1145 0 0
x_make_qstorno_1200_2000 5142 5142 0 0.00 3 76039 3311 0 0
x_make_qstorno_2100_3300 429897 429897 0 0.00 0 41813 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-11-2021 16:40:24 to 15-11-2021 16:40:28.

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 5 8192000000 8798900528 8810168320 5314912 0.064 2 1 2 0 5414512 56816 42784
14:08:59 25225 8192000000 9912415936 10229919744 995258720 10.833 100 90 180 0 1025309312 6484576 23566016
14:10:29 7888 8192000000 9956472528 10282938368 1041563360 11.280 100 89 178 0 1072368240 8434352 22370528
14:11:44 21978 8192000000 9990339616 10323628032 1069973968 11.552 100 88 176 0 1102049568 9764768 22310832
14:13:13 18412 8192000000 10040282944 10391638016 1128263520 12.105 100 81 162 0 1158338576 10422912 19652144
14:14:36 11119 8192000000 10085477664 10449723392 1172306416 12.518 100 67 134 0 1198573552 11021664 15245472
14:15:50 6139 8192000000 10127888880 10499624960 1214628224 12.912 100 75 150 1 1243799200 13048032 16122944
14:16:59 4578 8192000000 10166884064 10543775744 1249614224 13.235 100 81 162 2 1282095920 15186192 17295504
14:18:07 1832 8192000000 10197825152 10579894272 1280710992 13.520 100 82 164 1 1310604912 11139984 18753936
14:19:11 1615 8192000000 10214042512 10600701952 1302128048 13.715 100 93 185 0 1336653040 13407856 21117136
14:20:14 6240 8192000000 10192255440 10603249664 1304686560 13.738 100 40 79 3 1319991568 5562912 9742096
14:21:23 4022 8192000000 10198485824 10610724864 1310970576 13.795 100 36 73 3 1326799056 6843648 8984832
14:22:34 11798 8192000000 10202655968 10621677568 1320551008 13.882 100 23 46 2 1333198864 6721520 5926336
14:23:47 60083 8192000000 10213420384 10638102528 1326962032 13.940 100 35 70 0 1341154224 6332496 7859696
14:25:52 5960 8192000000 10231862000 10652778496 1334988112 14.012 100 23 45 1 1350725744 10641648 5095984
14:26:59 8869 8192000000 10218850992 10636402688 1316179248 13.842 99 24 47 1 1327376400 6147920 5049232
14:28:08 60142 8192000000 10185765264 10601771008 1234546800 13.096 93 22 44 0 1244702320 4753504 5402016
14:30:08 8329 8192000000 10154882768 10553802752 1229078688 13.046 97 16 33 1 1238619952 6130608 3410656
14:31:21 7196 8192000000 10130134832 10528829440 1201901360 12.794 96 21 42 0 1212280912 5128464 5251088
14:32:29 59066 8192000000 10146376144 10552631296 1241495072 13.160 98 27 54 0 1257621888 9519904 6606912
14:34:32 6271 8192000000 10174103664 10582937600 1282321776 13.534 100 22 44 2 1293214368 5699696 5192896
14:35:41 6973 8192000000 10186358288 10598739968 1294017328 13.641 100 25 50 1 1305736064 5895456 5823280
14:36:48 8332 8192000000 10198947328 10611175424 1303591232 13.728 100 28 54 2 1315036848 4822560 6623056
14:37:59 67877 8192000000 10232526752 10648883200 1330634448 13.973 100 32 64 1 1343471120 4827264 8009408
14:40:14 21805 8192000000 10273519792 10689724416 1361368112 14.250 100 51 101 1 1385502464 12957216 11177136
14:41:46 6205 8192000000 10300140976 10713128960 1375997584 14.381 100 60 119 0 1404671424 15199232 13474608
14:42:55 26915 8192000000 10306532304 10728378368 1388016272 14.488 100 48 95 1 1419962176 20854112 11091792
14:44:29 4523 8192000000 10345819440 10758176768 1405338544 14.643 100 57 114 1 1447432208 28186416 13907248
14:45:37 8681 8192000000 10356659392 10776141824 1418120656 14.756 100 68 136 0 1468608704 34410832 16077216
14:46:56 13516 8192000000 10364626688 10790264832 1421704464 14.788 100 43 85 0 1470661440 38299808 10657168
14:48:15 13351 8192000000 10384531280 10808061952 1435957376 14.914 100 51 100 1 1487659152 38796736 12905040
14:49:40 6219 8192000000 10393972784 10804170752 1435177968 14.907 100 71 143 2 1497044784 43955680 17911136
14:50:53 2803 8192000000 10407056832 10818355200 1437827728 14.930 100 87 174 0 1508892144 49485472 21578944
14:52:02 5608 8192000000 10392809392 10806452224 1421924208 14.790 100 71 142 0 1496485392 56183232 18377952
14:53:12 2056 8192000000 10401358832 10809470976 1414532720 14.724 99 84 169 2 1493720032 56593104 22594208
14:54:19 2842 8192000000 10405893280 10820624384 1433946832 14.896 100 76 152 1 1507006384 53238032 19821520
14:55:27 13522 8192000000 10378176176 10806194176 1416609728 14.743 100 40 80 1 1484704320 57384704 10709888
14:56:47 17304 8192000000 10366301584 10787979264 1400017664 14.595 100 42 81 2 1472614288 60926592 11670032
14:58:10 13216 8192000000 10365186080 10792468480 1392997696 14.533 100 40 80 1 1470068288 65614512 11456080
14:59:28 20218 8192000000 10373223104 10808242176 1402219344 14.615 100 41 81 1 1482671504 68431584 12020576
15:00:53 18684 8192000000 10372380928 10814361600 1404851392 14.638 100 38 76 1 1479225232 63406832 10967008
15:02:17 19046 8192000000 10349327792 10800918528 1391801584 14.522 100 19 38 2 1452256960 54739904 5715472
15:03:38 28730 8192000000 10299829232 10745401344 1324577296 13.918 96 16 32 1 1379397984 50599648 4221040
15:05:09 17642 8192000000 10294345552 10729467904 1371532320 14.341 100 52 105 1 1396083520 12026768 12524432
15:06:31 80031 8192000000 10270101200 10719518720 1343251312 14.087 98 17 34 1 1360452256 13591904 3609040
15:09:16 188395 8192000000 10268781968 10720145408 1283247648 13.543 93 16 32 0 1299140688 12677856 3215184
15:13:25 76415 8192000000 10284229904 10729758720 1363127760 14.265 99 17 34 0 1375335008 7788496 4418752
15:15:42 45953 8192000000 10287880512 10738495488 1374187264 14.365 100 15 30 0 1390689472 12411968 4090240
15:17:37 27444 8192000000 10288858416 10730147840 1357907424 14.219 99 51 100 1 1385792976 16301776 11583776
15:19:16 68247 8192000000 10273025664 10737991680 1375121776 14.373 100 12 24 0 1389560608 11648656 2790176
15:21:27 86959 8192000000 10284102976 10738364416 1375376976 14.375 100 13 26 0 1390502896 11465216 3660704
15:23:57 35087 8192000000 10272153200 10735419392 1368932464 14.317 100 22 46 0 1385182656 10815216 5434976
15:25:41 76721 8192000000 10269292576 10727190528 1369596752 14.323 99 17 34 1 1381485952 7867232 4021968
15:28:09 64824 8192000000 10281199040 10755465216 1382522688 14.439 100 8 16 0 1392182272 7965648 1693936
15:30:24 58271 8192000000 10287654032 10747199488 1371159936 14.337 99 14 28 0 1385654784 11007696 3487152
15:32:24 35240 8192000000 10304039264 10749685760 1385701872 14.467 100 32 64 1 1401523408 8662080 7159456
15:34:04 30443 8192000000 10289166416 10734796800 1347655040 14.126 97 35 71 0 1364349872 9255952 7438880
15:35:39 52461 8192000000 10305535696 10763964416 1388788096 14.495 100 36 72 0 1411089984 14138416 8163472
15:37:35 87546 8192000000 10290981440 10756362240 1346102384 14.112 96 14 28 1 1363896048 14778960 3014704
15:40:14 86044 8192000000 10295383648 10752458752 1353704240 14.181 97 18 36 0 1366897952 9509536 3684176
15:42:41 86034 8192000000 10291754912 10746372096 1316638480 13.846 95 23 44 2 1341621456 20098256 4884720
15:45:14 77901 8192000000 10285877344 10737115136 1334051312 14.004 96 22 44 0 1351137264 12254832 4831120
15:47:32 63577 8192000000 10271763520 10737065984 1339862112 14.056 97 23 46 0 1358096688 13207536 5027040
15:49:36 26504 8192000000 10266010000 10716966912 1353016064 14.175 99 16 32 2 1367337456 10537728 3783664
15:51:11 55588 8192000000 10262530240 10714357760 1334192912 14.005 97 20 39 2 1347548016 8465440 4889664
15:53:22 38404 8192000000 10266838400 10721816576 1331553360 13.981 98 23 46 3 1350641952 13784832 5303760
15:55:01 52701 8192000000 10279920496 10723037184 1350224288 14.149 99 31 62 1 1367204112 9794512 7185312
15:56:57 60434 8192000000 10286447632 10734637056 1341204608 14.068 98 16 32 0 1364756240 20085840 3465792
15:58:58 53571 8192000000 10280887632 10735841280 1354057696 14.184 98 29 58 0 1375072608 14317792 6697120
16:00:53 24334 8192000000 10272699392 10720419840 1345342384 14.106 98 34 68 0 1363498816 10402304 7754128
16:02:19 19156 8192000000 10314916320 10747314176 1382259824 14.437 100 58 116 0 1406148784 11116224 12772736
16:03:42 44086 8192000000 10283599616 10725687296 1333631680 14.000 97 23 46 0 1350967600 11918832 5417088
16:05:53 143680 8192000000 10285944176 10738053120 1367279024 14.303 99 24 48 2 1383761760 11146208 5336528
16:09:18 22397 8192000000 10270331120 10723946496 1362129520 14.256 99 17 34 0 1377275712 11690416 3455776
16:10:50 59024 8192000000 10274194208 10729938944 1371461760 14.340 100 8 16 0 1386077136 12998672 1616704
16:12:49 61717 8192000000 10285503616 10733379584 1340804400 14.065 97 14 28 1 1354345280 11215616 2325264
16:14:51 42068 8192000000 10304891696 10751512576 1383576992 14.449 100 28 56 2 1404357104 14027216 6752896
16:16:41 48428 8192000000 10299445584 10748530688 1369358240 14.321 99 20 38 2 1393918048 19754800 4805008
16:18:37 76886 8192000000 10294627712 10752552960 1362992448 14.264 99 9 18 0 1379254544 13897696 2364400
16:20:59 30826 8192000000 10295601472 10743599104 1362042304 14.256 98 32 64 0 1380748272 10873072 7832896
16:22:30 99226 8192000000 10312487984 10754891776 1371554944 14.341 99 24 48 1 1395135200 17828256 5752000
16:25:16 71876 8192000000 10289169072 10741313536 1323417744 13.908 96 19 38 1 1342533504 14954064 4161696
16:27:33 56372 8192000000 10288541072 10753126400 1378374512 14.402 100 9 18 0 1394833824 14455888 2003424
16:29:34 75679 8192000000 10276338880 10735960064 1352835040 14.173 98 17 34 2 1374780064 18506368 3438656
16:31:51 61738 8192000000 10276439392 10744745984 1369858512 14.326 100 4 8 0 1385687904 14803680 1025712
16:33:54 68607 8192000000 10267027360 10715996160 1322850288 13.903 96 9 18 0 1336963568 11749248 2364032
16:36:04 25500 8192000000 10260303712 10716348416 1344642288 14.099 99 17 34 0 1361867632 12784944 4440400
16:37:32 34942 8192000000 9261958576 9542352896 422328512 4.902 31 7 11 1 427145872 3164784 1652576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-11-2021 16:40:30 to 15-11-2021 16:40:45.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-11-2021 16:40:46 to 15-11-2021 16:40:48.

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          228381
MON$OLDEST_ACTIVE               228908
MON$OLDEST_SNAPSHOT             228908
MON$NEXT_TRANSACTION            228908
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                       2012736
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:346: 
:347: mscs-test-161.budzdorov.ru    Mon Nov 15 16:38:18 2021
:348:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:349: 
:350: 
:351: mscs-test-161.budzdorov.ru    Mon Nov 15 16:38:20 2021
:352:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:353: 
:354: 
:355: mscs-test-161.budzdorov.ru    Mon Nov 15 16:38:21 2021
:356:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:357: 
:358: 
:359: mscs-test-161.budzdorov.ru    Mon Nov 15 16:38:23 2021
:360:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:361: 
--- end of diff output ---

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

15.11.21 16:40:49. 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-11-15 16:40:51 No severe PSQL-related problems occured
15.11.21 16:40:52. End of report.