Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.11.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 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 16993524 kB
MemAvailable 31595648 kB
Buffers 0 kB
Cached 14715760 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-22 22:37:51
DTS_END                         2021-11-22 22:37:51.4250
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/47308
TRN_ID                          239427
ATT_ID                          699
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: 9130.94 18010 1095713
customer order: creation 172.69 394 20723
customer order: refuse 14.61 357 1753
order to supplier: creation 45.83 1369 5500
order to supplier: removal 3.53 90208 423
invoice (draft): creation 44.64 3473 5357
invoice (draft): removal 51.86 683 6223
invoice accept: apply 41.48 17387 4977
invoice accept: cancel 47.52 786 5702
customer reserve: creation 3749.88 177 449985
customer reserve: removal 4653.48 54 558418
realization accept: apply 82.39 177 9887
realization accept: cancel 93.56 102 11227
payment from customer: creation 58.74 119 7049
payment from customer: removal 5.56 62 667
payment to supplier: creation 49.88 133 5986
payment to supplier: removal 3.23 75 388
service: total inventory turnovers 4.41 1113 529
service: total monetary turnovers 6.73 24 808
service: aggregate perf. data 0.89 20431 107
service: refresh index statistics 0.03 223083 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-11-2021 22:39:54 to 22-11-2021 22:39:56.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3149
TEST_TIME 2 3852
TEST_TIME 3 4938
TEST_TIME 4 4775
TEST_TIME 5 4962
TEST_TIME 6 4915
TEST_TIME 7 4753
TEST_TIME 8 4845
TEST_TIME 9 4960
TEST_TIME 10 5130
TEST_TIME 11 5345
TEST_TIME 12 5476
TEST_TIME 13 5654
TEST_TIME 14 5732
TEST_TIME 15 6271
TEST_TIME 16 7163
TEST_TIME 17 7612
TEST_TIME 18 7870
TEST_TIME 19 8099
TEST_TIME 20 8553
TEST_TIME 21 8480
TEST_TIME 22 9397
TEST_TIME 23 9321
TEST_TIME 24 9787
TEST_TIME 25 10333
TEST_TIME 26 10255
TEST_TIME 27 10470
TEST_TIME 28 10649
TEST_TIME 29 10674
TEST_TIME 30 10590
TEST_TIME 31 10346
TEST_TIME 32 10411
TEST_TIME 33 10416
TEST_TIME 34 10306
TEST_TIME 35 10165
TEST_TIME 36 10144
TEST_TIME 37 10050
TEST_TIME 38 9900
TEST_TIME 39 9727
TEST_TIME 40 9699
TEST_TIME 41 9601
TEST_TIME 42 9541
TEST_TIME 43 9460
TEST_TIME 44 9493
TEST_TIME 45 9511
TEST_TIME 46 9501
TEST_TIME 47 9501
TEST_TIME 48 9388
TEST_TIME 49 9345
TEST_TIME 50 9227
TEST_TIME 51 9179
TEST_TIME 52 9183
TEST_TIME 53 9044
TEST_TIME 54 9120
TEST_TIME 55 9095
TEST_TIME 56 9072
TEST_TIME 57 9129
TEST_TIME 58 9082
TEST_TIME 59 9252
TEST_TIME 60 9251
TEST_TIME 61 9225
TEST_TIME 62 9244
TEST_TIME 63 9433
TEST_TIME 64 9387
TEST_TIME 65 9437
TEST_TIME 66 9454
TEST_TIME 67 9439
TEST_TIME 68 9565
TEST_TIME 69 9546
TEST_TIME 70 9564
TEST_TIME 71 9450
TEST_TIME 72 9424
TEST_TIME 73 9389
TEST_TIME 74 9362
TEST_TIME 75 9347
TEST_TIME 76 9296
TEST_TIME 77 9257
TEST_TIME 78 9227
TEST_TIME 79 9131
TEST_TIME 80 9138
TEST_TIME 81 9146
TEST_TIME 82 9098
TEST_TIME 83 9171
TEST_TIME 84 9163
TEST_TIME 85 9203
TEST_TIME 86 9213
TEST_TIME 87 9303
TEST_TIME 88 9315
TEST_TIME 89 9278
TEST_TIME 90 9320
TEST_TIME 91 9303
TEST_TIME 92 9330
TEST_TIME 93 9346
TEST_TIME 94 9446
TEST_TIME 95 9352
TEST_TIME 96 9290
TEST_TIME 97 9296
TEST_TIME 98 9255
TEST_TIME 99 9174
TEST_TIME 100 9147
TEST_TIME 101 9099
TEST_TIME 102 9166
TEST_TIME 103 9138
TEST_TIME 104 9118
TEST_TIME 105 9141
TEST_TIME 106 9142
TEST_TIME 107 9150
TEST_TIME 108 9159
TEST_TIME 109 9142
TEST_TIME 110 9151
TEST_TIME 111 9174
TEST_TIME 112 9238
TEST_TIME 113 9221
TEST_TIME 114 9257
TEST_TIME 115 9292
TEST_TIME 116 9278
TEST_TIME 117 9215
TEST_TIME 118 9206
TEST_TIME 119 9173
TEST_TIME 120 9134

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-11-2021 22:39:57 to 22-11-2021 22:40:01.

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 1513808 1513808 0 0.00 0 22037 6 0 0
doc_list_biud 1513808 1513808 0 0.00 0 51358 25 0 0
sp_add_invoice_to_stock 5135 4977 158 3.07 19 228868 17388 0 158
sp_cancel_adding_invoice 5702 5702 0 0.00 4 58593 786 0 0
sp_cancel_client_order 1753 1753 0 0.00 4 19879 357 0 0
sp_cancel_customer_reserve 558418 558418 0 0.00 1 51361 54 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 5944 63 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 2 5835 76 0 0
sp_cancel_supplier_invoice 6238 6223 15 0.24 2 14641 683 0 15
sp_cancel_supplier_order 423 423 0 0.00 5 977066 90209 0 0
sp_cancel_write_off 11227 11227 0 0.00 1 13607 102 0 0
sp_client_order 20723 20723 0 0.00 3 56469 395 0 0
sp_customer_reserve 449985 449985 0 0.00 0 43357 178 0 0
sp_fill_shopping_cart 31566 31566 0 0.00 1 16475 39 0 0
sp_fill_shopping_cart_clo_res 449980 449980 0 0.00 0 20932 15 0 0
sp_get_clo_for_invoice 4972 4972 0 0.00 0 39231 325 0 0
sp_kill_cost_storno 17984 17984 0 0.00 0 22028 53 0 0
sp_kill_qstorno_ret_qs2qd 1134709 1134709 0 0.00 0 51350 29 0 0
sp_kill_qty_storno 598605 598605 0 0.00 0 51358 64 0 0
sp_lock_selected_doc 20562 20562 0 0.00 0 9107 6 0 0
sp_make_cost_storno 27894 27894 0 0.00 0 16697 57 0 0
sp_multiply_rows_for_pdistr 27894 27894 0 0.00 0 10307 26 0 0
sp_multiply_rows_for_qdistr 20723 20723 0 0.00 0 47769 176 0 0
sp_pay_from_customer 7049 7049 0 0.00 4 33968 120 0 0
sp_pay_to_supplier 5986 5986 0 0.00 5 16970 134 0 0
sp_payment_common 13035 13035 0 0.00 1 32638 29 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 0 19866 322 0 0
sp_qd_handle_on_invoice_upd_sts 10674 10674 0 0.00 0 31462 310 0 0
sp_qd_handle_on_reserve_upd_sts 9887 9887 0 0.00 0 18422 64 0 0
sp_reserve_write_off 9887 9887 0 0.00 6 18568 178 0 0
sp_supplier_invoice 5357 5357 0 0.00 6 96531 3474 0 0
sp_supplier_order 5500 5500 0 0.00 5 98117 1369 0 0
srv_aggregate_perf_data 107 107 0 0.00 2543 294254 20431 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1503 150436 48849 0 0
srv_find_qd_qs_mism 1049169 1049169 0 0.00 0 15836 5 0 0
srv_make_invnt_saldo 529 529 0 0.00 45 68431 1113 0 0
srv_make_money_saldo 808 808 0 0.00 1 1054 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 77030 511146 223083 0 0
t$perf-abend:sp_add_invoice_to_stock 158 158 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4977 4977 0 0.00 0 13196 166 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 3916 90 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 5727 19 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 1151 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 1819 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1722 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 3724 26 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 32566 1522 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 8658 17 0 0
t$perf-norm:sp_client_order 20723 20723 0 0.00 0 10034 22 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 10034 19 0 0
t$perf-norm:sp_pay_from_customer 7049 7049 0 0.00 0 10375 14 0 0
t$perf-norm:sp_pay_to_supplier 5986 5986 0 0.00 0 20807 24 0 0
t$perf-norm:sp_reserve_write_off 9887 9887 0 0.00 0 7393 15 0 0
t$perf-norm:sp_supplier_invoice 5357 5357 0 0.00 0 6818 17 0 0
t$perf-norm:sp_supplier_order 5500 5500 0 0.00 0 7079 20 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 207 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1318 21 0 0
t$perf-norm:srv_make_invnt_saldo 529 529 0 0.00 0 3465 28 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1590 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10252 9936 316 3.08 0 4216 16 0 316
v_all_customers 37614 37614 0 0.00 0 165 0 0 0
v_all_suppliers 30558 30558 0 0.00 0 28 1 0 0
v_all_wares 41442 41442 0 0.00 0 160 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 578 6 0 0
v_cancel_client_order 3506 3506 0 0.00 0 3573 9 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 1515 4 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 1593 4 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 5791 14 0 0
v_cancel_supplier_invoice 816 786 30 3.67 0 1725 17 0 30
v_cancel_supplier_order 846 846 0 0.00 0 1078 4 0 0
v_cancel_write_off 1920 1920 0 0.00 0 339 9 0 0
v_max_id_clo_ord 5497 5497 0 0.00 0 2459 2 0 0
v_max_id_clo_res 981 981 0 0.00 0 4399 19 0 0
v_max_id_ord_sup 5347 5347 0 0.00 0 8271 7 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 904 4 0 0
v_max_non_paid_realizn 3736 3736 0 0.00 0 1143 3 0 0
v_min_id_clo_ord 5497 5497 0 0.00 0 3134 6 0 0
v_min_id_clo_res 981 981 0 0.00 0 7304 18 0 0
v_min_id_ord_sup 5347 5347 0 0.00 0 6666 11 0 0
v_min_non_paid_invoice 5986 5986 0 0.00 0 5759 18 0 0
v_min_non_paid_realizn 7049 7049 0 0.00 0 8721 23 0 0
v_our_firm 10454 10454 0 0.00 0 21 1 0 0
v_reserve_write_off 19774 19774 0 0.00 0 5790 7 0 0
x_lock_depdocs_on_canc_invoice 11532 11532 0 0.00 0 21314 108 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 21281 400 0 0
x_make_qstorno_1000_1200 5500 5500 0 0.00 2 98067 1293 0 0
x_make_qstorno_1200_2000 5357 5357 0 0.00 3 92222 3325 0 0
x_make_qstorno_2100_3300 449985 449985 0 0.00 0 43356 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-11-2021 22:40:02 to 22-11-2021 22:40:06.

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:55 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:56 4580 8192000000 9880025744 10185433088 972094704 10.607 100 89 178 0 1001586528 5625392 23866432
20:10:13 20857 8192000000 9935019056 10253357056 1013158240 11.006 100 87 174 0 1043700848 7714752 22827856
20:11:43 18299 8192000000 9973659936 10302492672 1058003840 11.437 100 87 174 0 1090094496 9604528 22486128
20:13:07 11637 8192000000 10013551808 10360393728 1100015568 11.838 100 76 151 1 1128291344 10476944 17798832
20:14:23 9373 8192000000 10075866176 10432069632 1164314384 12.444 100 81 163 0 1194195728 11819040 18062304
20:15:35 5031 8192000000 10129933248 10496520192 1212407936 12.891 100 84 167 3 1245422752 14736800 18278016
20:16:42 4346 8192000000 10169049024 10542956544 1250991344 13.247 100 86 172 0 1285599408 14661504 19946560
20:17:48 3149 8192000000 10199090304 10581659648 1284238624 13.552 100 90 179 1 1316970880 13211808 19520448
20:18:52 1628 8192000000 10230814960 10618519552 1311928784 13.804 100 92 184 1 1345147472 12126912 21091776
20:19:54 30177 8192000000 10235122560 10629169152 1323229872 13.906 100 38 77 0 1337852528 5512688 9109968
20:21:25 9607 8192000000 10215296448 10634452992 1328673568 13.955 100 26 52 0 1341577456 6762000 6141888
20:22:35 6869 8192000000 10227457360 10644422656 1336937312 14.030 100 36 72 2 1351975760 6581792 8456656
20:23:43 5283 8192000000 10227048496 10650628096 1340011808 14.058 100 26 53 1 1352370048 6484528 5873712
20:24:50 57302 8192000000 10257221824 10668527616 1346209808 14.113 100 49 98 0 1364053536 6510304 11333424
20:26:52 7261 8192000000 10230142320 10656096256 1340409232 14.061 100 23 46 1 1351356096 6018960 4927904
20:27:59 8562 8192000000 10222839424 10641997824 1312154224 13.806 99 23 46 1 1328101056 9393200 6553632
20:29:12 7301 8192000000 10163708640 10569035776 1262131568 13.350 99 17 34 1 1272478352 6497600 3849184
20:30:20 37343 8192000000 10135079456 10529910784 1156951120 12.375 91 26 52 0 1167323024 4105264 6266640
20:31:59 7520 8192000000 10152591776 10552524800 1245705968 13.199 99 33 66 0 1260355360 6944288 7705104
20:33:08 12212 8192000000 10169203008 10579320832 1277928544 13.494 100 18 36 0 1286981568 5133760 3919264
20:34:22 6163 8192000000 10184457376 10595483648 1292067024 13.623 100 17 34 1 1302052624 5646048 4339552
20:35:29 23449 8192000000 10183859040 10604347392 1298584544 13.682 100 36 72 0 1313028976 6146624 8297808
20:36:53 9550 8192000000 10206720224 10617233408 1309384176 13.780 100 28 57 1 1320814848 5610768 5819904
20:38:06 26997 8192000000 10241093216 10650214400 1330469856 13.971 100 36 71 1 1344502960 5787472 8245632
20:39:38 1909 8192000000 10262138224 10669568000 1348800752 14.137 100 60 121 1 1374483920 12040096 13643072
20:40:43 59388 8192000000 10272840528 10694549504 1363401088 14.268 100 41 82 1 1389882960 16615712 9866160
20:42:45 4946 8192000000 10296357120 10723450880 1383614560 14.449 100 45 90 0 1414078992 19723248 10741184
20:44:00 8135 8192000000 10306196656 10732032000 1389873760 14.505 100 49 98 1 1422660848 20668752 12118336
20:45:13 5728 8192000000 10321691264 10744741888 1396269344 14.562 100 54 107 1 1433232000 24599328 12363328
20:46:25 4020 8192000000 10344834944 10762842112 1406843168 14.656 100 72 138 2 1453454128 29430880 17180080
20:47:32 14862 8192000000 10348626496 10772393984 1408916624 14.674 100 57 114 0 1460646864 37556528 14173712
20:49:02 6106 8192000000 10360506000 10789130240 1420107296 14.774 100 45 89 0 1471051088 39391872 11551920
20:50:11 2261 8192000000 10372949168 10788995072 1413466496 14.715 100 80 161 0 1476972624 43997232 19508896
20:51:16 8993 8192000000 10367989760 10785075200 1397708240 14.575 99 70 140 1 1464115952 48401472 18006240
20:52:36 4981 8192000000 10356536896 10775715840 1392196096 14.525 99 49 98 1 1459383488 54148384 13039008
20:53:44 1502 8192000000 10359711376 10776375296 1389723728 14.503 100 85 167 0 1462375408 51511024 21140656
20:54:49 13017 8192000000 10329123184 10758868992 1376395024 14.384 99 43 86 0 1431961424 44491024 11075376
20:56:06 16209 8192000000 10306783280 10734673920 1352017744 14.166 99 27 54 1 1403762080 44345792 7398544
20:57:24 24703 8192000000 10299442624 10734641152 1355485744 14.197 99 22 44 0 1400030736 38617616 5927376
20:58:58 6982 8192000000 10324708144 10752397312 1374821920 14.370 100 41 81 0 1430609728 45082544 10705264
21:00:07 19610 8192000000 10296728944 10728402944 1342285824 14.078 97 23 46 0 1375361168 27059216 6016128
21:01:31 9747 8192000000 10311460608 10752987136 1362815152 14.263 99 24 48 1 1411374320 41075600 7483568
21:02:44 53648 8192000000 10276222544 10725122048 1338074416 14.040 98 18 37 2 1369403456 26680976 4648064
21:05:14 19483 8192000000 10275436880 10720505856 1362384896 14.259 100 16 32 0 1383246176 17110272 3751008
21:06:34 88141 8192000000 10281694272 10741571584 1352223520 14.167 98 17 32 1 1369497968 13671760 3602688
21:09:04 117979 8192000000 10270367568 10726653952 1313374256 13.817 95 9 18 1 1327954128 12816608 1763264
21:12:03 62383 8192000000 10284125120 10733916160 1377400768 14.393 100 24 48 1 1393611904 10808000 5403136
21:14:06 20188 8192000000 10298592896 10739552256 1386648704 14.476 100 52 104 1 1406901088 8798608 11453776
21:15:36 84484 8192000000 10276450416 10727763968 1366667440 14.297 99 24 48 0 1380926096 8090816 6167840
21:18:03 35108 8192000000 10301487440 10746056704 1380578688 14.422 99 42 81 0 1400891696 10464304 9848704
21:19:49 55097 8192000000 10294378112 10754748416 1389016240 14.497 100 10 20 0 1406740704 15225360 2499104
21:21:45 57169 8192000000 10283472336 10747281408 1381516272 14.430 99 14 28 1 1398466672 13378736 3571664
21:23:44 46557 8192000000 10278948400 10734637056 1362299408 14.258 98 17 36 0 1373841472 7752176 3789888
21:25:33 120899 8192000000 10289235408 10747965440 1391493952 14.519 100 18 36 0 1408309280 12949888 3865440
21:28:43 51018 8192000000 10303882272 10763231232 1400434624 14.599 100 14 28 1 1413357056 9332224 3590208
21:30:46 50125 8192000000 10306378272 10774806528 1410962048 14.692 100 13 26 0 1427010288 12916400 3131840
21:32:40 34339 8192000000 10314836384 10766708736 1403833360 14.629 100 19 38 0 1414854512 6580848 4440304
21:34:24 30258 8192000000 10277072784 10743529472 1362989104 14.264 97 18 36 0 1377480752 10520080 3971568
21:35:54 75949 8192000000 10304698880 10786754560 1403114480 14.623 100 24 48 0 1427822576 19419168 5288928
21:38:32 31665 8192000000 10317356144 10763853824 1380040560 14.417 99 33 66 0 1403055312 16099712 6915040
21:40:09 86990 8192000000 10291421104 10741567488 1343207680 14.086 96 16 32 0 1360048256 13801936 3038640
21:42:39 72187 8192000000 10267532304 10710638592 1271526464 13.436 92 11 22 0 1284417856 10250336 2641056
21:45:11 115323 8192000000 10279263808 10730717184 1302141376 13.715 95 11 22 0 1321304368 16865728 2297264
21:48:14 150435 8192000000 10257162736 10701279232 1312736880 13.811 97 31 61 1 1337157296 17319360 7101056
21:52:01 15805 8192000000 10250189424 10691493888 1330957312 13.976 98 36 70 1 1347644048 8272864 8413872
21:53:18 37683 8192000000 10229073536 10673975296 1322796688 13.902 98 8 16 0 1330527248 6207968 1522592
21:54:57 71820 8192000000 10225118736 10668187648 1273632048 13.455 94 20 40 0 1286217680 7354144 5231488
21:57:09 61439 8192000000 10234097888 10696290304 1346077904 14.112 100 17 34 1 1360233568 10485200 3670464
21:59:19 55545 8192000000 10246332192 10692382720 1317433536 13.853 97 18 36 1 1331707584 9764816 4509232
22:01:15 19695 8192000000 10248084816 10686967808 1324636224 13.919 98 31 63 1 1338882064 6843056 7402784
22:02:35 85836 8192000000 10266381120 10701881344 1307421280 13.763 96 21 41 1 1324486832 11232304 5833248
22:05:14 62776 8192000000 10266610560 10708779008 1349630320 14.144 99 20 40 0 1363939120 9932640 4376160
22:07:25 63959 8192000000 10289325408 10724147200 1372158576 14.346 100 31 62 0 1390634720 12238096 6238048
22:09:32 64062 8192000000 10300605600 10739789824 1343103552 14.085 98 37 74 0 1365119984 14259424 7757008
22:11:42 137065 8192000000 10280085280 10735570944 1384458624 14.456 100 9 18 0 1411410464 24557232 2394608
22:15:02 52365 8192000000 10276158384 10734735360 1360777280 14.244 98 10 20 0 1370968144 7326720 2864144
22:16:56 59534 8192000000 10283603104 10745303040 1361992576 14.255 98 17 36 0 1376509968 10043456 4473936
22:18:55 97057 8192000000 10282855104 10755710976 1362439984 14.259 98 13 27 0 1380687168 15397312 2849872
22:21:33 61672 8192000000 10274061728 10717220864 1320740144 13.883 95 15 30 0 1338242576 14341632 3160800
22:23:51 55989 8192000000 10287613056 10739060736 1343421792 14.088 97 28 54 2 1358874480 9497120 5955568
22:25:55 6833 8192000000 10301283200 10751475712 1393334032 14.536 100 51 99 3 1416447168 11215904 11897232
22:27:12 51934 8192000000 10292565344 10736545792 1322678848 13.901 95 27 54 1 1345738272 17312976 5746448
22:29:05 93717 8192000000 10297021552 10736111616 1364658176 14.279 99 15 30 1 1390774704 23139536 2976992
22:31:39 61940 8192000000 10297342096 10750812160 1375134560 14.373 99 23 44 2 1393699216 13455792 5108864
22:33:48 101271 8192000000 10277431008 10726150144 1371206896 14.338 99 25 50 0 1387662576 11079792 5375888
22:36:33 59850 8192000000 10266432960 10729648128 1357062368 14.211 98 6 12 1 1366904512 8897968 944176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 22-11-2021 22:40:08 to 22-11-2021 22:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 158 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-11-2021 22:40:23 to 22-11-2021 22:40:25.

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          238955
MON$OLDEST_ACTIVE               239547
MON$OLDEST_SNAPSHOT             239547
MON$NEXT_TRANSACTION            239547
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                       2011136
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:204: 
:205: mscs-test-161.budzdorov.ru    Mon Nov 22 22:37:53 2021
:206:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:207: 
:208: 
:209: mscs-test-161.budzdorov.ru    Mon Nov 22 22:38:06 2021
:210:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:211: 
:212: 
:213: mscs-test-161.budzdorov.ru    Mon Nov 22 22:38:12 2021
:214:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:215: 
:216: 
:217: mscs-test-161.budzdorov.ru    Mon Nov 22 22:38:17 2021
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:219: 
--- end of diff output ---

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

22.11.21 22:40:26. 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-22 22:40:29 No severe PSQL-related problems occured
22.11.21 22:40:30. End of report.