Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.12.2021 07: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 16527956 kB
MemAvailable 31401516 kB
Buffers 0 kB
Cached 15192528 kB
SwapCached 2388 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-12-30 07:37:10
DTS_END                         2021-12-30 07:37:10.0350
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(68:17)
IP                              ::1/53962
TRN_ID                          231083
ATT_ID                          656
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: 9106.70 26052 1092804
customer order: creation 163.34 390 19601
customer order: refuse 14.23 305 1707
order to supplier: creation 44.25 1415 5310
order to supplier: removal 3.33 107515 400
invoice (draft): creation 45.03 3488 5403
invoice (draft): removal 51.72 869 6206
invoice accept: apply 40.10 19669 4812
invoice accept: cancel 46.85 851 5622
customer reserve: creation 3748.84 192 449861
customer reserve: removal 4661.88 60 559426
realization accept: apply 78.38 208 9406
realization accept: cancel 85.53 115 10263
payment from customer: creation 55.72 127 6686
payment from customer: removal 5.48 101 658
payment to supplier: creation 47.76 126 5731
payment to supplier: removal 3.40 91 408
service: total inventory turnovers 3.77 3326 452
service: total monetary turnovers 6.27 439 752
service: aggregate perf. data 0.80 15757 96
service: refresh index statistics 0.03 365990 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-12-2021 07:39:22 to 30-12-2021 07:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5466
TEST_TIME 2 4134
TEST_TIME 3 3404
TEST_TIME 4 3623
TEST_TIME 5 3872
TEST_TIME 6 4604
TEST_TIME 7 5451
TEST_TIME 8 5408
TEST_TIME 9 5785
TEST_TIME 10 6566
TEST_TIME 11 6633
TEST_TIME 12 7080
TEST_TIME 13 7205
TEST_TIME 14 7230
TEST_TIME 15 7674
TEST_TIME 16 8294
TEST_TIME 17 8326
TEST_TIME 18 8867
TEST_TIME 19 9140
TEST_TIME 20 9142
TEST_TIME 21 9022
TEST_TIME 22 9104
TEST_TIME 23 9222
TEST_TIME 24 9181
TEST_TIME 25 10124
TEST_TIME 26 10152
TEST_TIME 27 10607
TEST_TIME 28 10369
TEST_TIME 29 10527
TEST_TIME 30 10605
TEST_TIME 31 10398
TEST_TIME 32 10182
TEST_TIME 33 10318
TEST_TIME 34 10243
TEST_TIME 35 10166
TEST_TIME 36 10017
TEST_TIME 37 9869
TEST_TIME 38 9794
TEST_TIME 39 9684
TEST_TIME 40 9738
TEST_TIME 41 9558
TEST_TIME 42 9591
TEST_TIME 43 9704
TEST_TIME 44 9676
TEST_TIME 45 9676
TEST_TIME 46 9938
TEST_TIME 47 9877
TEST_TIME 48 9840
TEST_TIME 49 9817
TEST_TIME 50 9884
TEST_TIME 51 9840
TEST_TIME 52 9750
TEST_TIME 53 9953
TEST_TIME 54 9847
TEST_TIME 55 9726
TEST_TIME 56 9667
TEST_TIME 57 9619
TEST_TIME 58 9542
TEST_TIME 59 9493
TEST_TIME 60 9520
TEST_TIME 61 9469
TEST_TIME 62 9479
TEST_TIME 63 9411
TEST_TIME 64 9382
TEST_TIME 65 9357
TEST_TIME 66 9298
TEST_TIME 67 9402
TEST_TIME 68 9436
TEST_TIME 69 9411
TEST_TIME 70 9521
TEST_TIME 71 9554
TEST_TIME 72 9516
TEST_TIME 73 9491
TEST_TIME 74 9531
TEST_TIME 75 9617
TEST_TIME 76 9641
TEST_TIME 77 9676
TEST_TIME 78 9570
TEST_TIME 79 9512
TEST_TIME 80 9428
TEST_TIME 81 9400
TEST_TIME 82 9376
TEST_TIME 83 9340
TEST_TIME 84 9288
TEST_TIME 85 9230
TEST_TIME 86 9234
TEST_TIME 87 9263
TEST_TIME 88 9233
TEST_TIME 89 9241
TEST_TIME 90 9210
TEST_TIME 91 9222
TEST_TIME 92 9367
TEST_TIME 93 9395
TEST_TIME 94 9402
TEST_TIME 95 9411
TEST_TIME 96 9444
TEST_TIME 97 9442
TEST_TIME 98 9427
TEST_TIME 99 9376
TEST_TIME 100 9332
TEST_TIME 101 9294
TEST_TIME 102 9284
TEST_TIME 103 9226
TEST_TIME 104 9219
TEST_TIME 105 9168
TEST_TIME 106 9171
TEST_TIME 107 9187
TEST_TIME 108 9176
TEST_TIME 109 9136
TEST_TIME 110 9197
TEST_TIME 111 9173
TEST_TIME 112 9170
TEST_TIME 113 9194
TEST_TIME 114 9183
TEST_TIME 115 9246
TEST_TIME 116 9269
TEST_TIME 117 9286
TEST_TIME 118 9226
TEST_TIME 119 9166
TEST_TIME 120 9108

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-12-2021 07:39:26 to 30-12-2021 07:39:29.

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 1508359 1508359 0 0.00 0 24930 7 0 0
doc_list_biud 1508359 1508359 0 0.00 0 41403 29 0 0
sp_add_invoice_to_stock 4906 4812 94 1.91 34 184956 19669 0 94
sp_cancel_adding_invoice 5622 5622 0 0.00 7 66517 852 0 0
sp_cancel_client_order 1707 1707 0 0.00 4 11145 306 0 0
sp_cancel_customer_reserve 559426 559426 0 0.00 1 29983 61 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 2 14281 102 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 4177 92 0 0
sp_cancel_supplier_invoice 6213 6206 7 0.11 3 41408 870 0 7
sp_cancel_supplier_order 400 400 0 0.00 10 852193 107515 0 0
sp_cancel_write_off 10263 10263 0 0.00 1 10685 115 0 0
sp_client_order 19601 19601 0 0.00 4 29979 391 0 0
sp_customer_reserve 449861 449861 0 0.00 0 37818 192 0 0
sp_fill_shopping_cart 30307 30307 0 0.00 1 17087 42 0 0
sp_fill_shopping_cart_clo_res 449859 449859 0 0.00 0 15033 16 0 0
sp_get_clo_for_invoice 4810 4810 0 0.00 0 30647 378 0 0
sp_kill_cost_storno 16951 16951 0 0.00 0 14266 57 0 0
sp_kill_qstorno_ret_qs2qd 1135721 1135721 0 0.00 0 41402 33 0 0
sp_kill_qty_storno 597840 597840 0 0.00 0 41403 71 0 0
sp_lock_selected_doc 19774 19774 0 0.00 0 9479 8 0 0
sp_make_cost_storno 26633 26633 0 0.00 0 20585 62 0 0
sp_multiply_rows_for_pdistr 26633 26633 0 0.00 0 14091 34 0 0
sp_multiply_rows_for_qdistr 19601 19601 0 0.00 0 11604 190 0 0
sp_pay_from_customer 6686 6686 0 0.00 4 25979 127 0 0
sp_pay_to_supplier 5731 5731 0 0.00 5 20244 126 0 0
sp_payment_common 12417 12417 0 0.00 1 25968 25 0 0
sp_qd_handle_on_cancel_clo 1707 1707 0 0.00 0 11130 274 0 0
sp_qd_handle_on_invoice_upd_sts 10432 10432 0 0.00 0 30196 344 0 0
sp_qd_handle_on_reserve_upd_sts 9406 9406 0 0.00 0 30005 68 0 0
sp_reserve_write_off 9406 9406 0 0.00 7 30087 208 0 0
sp_supplier_invoice 5403 5403 0 0.00 7 131435 3489 0 0
sp_supplier_order 5310 5310 0 0.00 5 59647 1415 0 0
srv_aggregate_perf_data 96 96 0 0.00 2730 166504 15757 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1127 126089 49185 0 0
srv_find_qd_qs_mism 1046362 1046362 0 0.00 0 18815 6 0 0
srv_make_invnt_saldo 452 452 0 0.00 41 93943 3327 0 0
srv_make_money_saldo 752 752 0 0.00 1 14175 440 0 0
srv_recalc_idx_stat 4 4 0 0.00 282994 470206 365990 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 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 4812 4812 0 0.00 0 12427 154 0 0
t$perf-norm:sp_cancel_adding_invoice 363 363 0 0.00 0 3769 74 0 0
t$perf-norm:sp_cancel_client_order 1707 1707 0 0.00 0 5060 16 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 1292 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 1162 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 3586 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 1478 20 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 19028 1335 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 391 4 0 0
t$perf-norm:sp_client_order 19601 19601 0 0.00 0 26084 17 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 599 4 0 0
t$perf-norm:sp_pay_from_customer 6686 6686 0 0.00 0 5933 8 0 0
t$perf-norm:sp_pay_to_supplier 5731 5731 0 0.00 0 9097 10 0 0
t$perf-norm:sp_reserve_write_off 9406 9406 0 0.00 0 28011 13 0 0
t$perf-norm:sp_supplier_invoice 5403 5403 0 0.00 0 11997 14 0 0
t$perf-norm:sp_supplier_order 5310 5310 0 0.00 0 7094 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 334 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 614 11 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 2986 13 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 1574 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 29 9 0 0
v_add_invoice_to_stock 9802 9614 188 1.91 0 5041 17 0 188
v_all_customers 35652 35652 0 0.00 0 18 1 0 0
v_all_suppliers 29820 29820 0 0.00 0 33 1 0 0
v_all_wares 39202 39202 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 726 726 0 0.00 0 1673 10 0 0
v_cancel_client_order 3414 3414 0 0.00 0 2398 6 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 2599 7 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2799 9 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 786 6 0 0
v_cancel_supplier_invoice 840 826 14 1.66 0 1518 17 0 14
v_cancel_supplier_order 800 800 0 0.00 0 1053 4 0 0
v_cancel_write_off 1882 1882 0 0.00 0 2329 13 0 0
v_max_id_clo_ord 5306 5306 0 0.00 0 657 1 0 0
v_max_id_clo_res 944 944 0 0.00 0 2522 14 0 0
v_max_id_ord_sup 5399 5399 0 0.00 0 12805 9 0 0
v_max_non_paid_invoice 1526 1526 0 0.00 0 2365 4 0 0
v_max_non_paid_realizn 3546 3546 0 0.00 0 1424 4 0 0
v_min_id_clo_ord 5306 5306 0 0.00 0 4806 7 0 0
v_min_id_clo_res 944 944 0 0.00 0 7934 24 0 0
v_min_id_ord_sup 5399 5399 0 0.00 0 9694 13 0 0
v_min_non_paid_invoice 5731 5731 0 0.00 0 5073 11 0 0
v_min_non_paid_realizn 6686 6686 0 0.00 0 10884 17 0 0
v_our_firm 9830 9830 0 0.00 0 14 1 0 0
v_reserve_write_off 18812 18812 0 0.00 0 4361 7 0 0
x_lock_depdocs_on_canc_invoice 11415 11415 0 0.00 0 28984 121 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 2373 280 0 0
x_make_qstorno_1000_1200 5310 5310 0 0.00 2 59640 1340 0 0
x_make_qstorno_1200_2000 5403 5403 0 0.00 3 131425 3332 0 0
x_make_qstorno_2100_3300 449861 449861 0 0.00 0 35621 174 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 30-12-2021 07:39:30 to 30-12-2021 07:39:35.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:14 29517 8192000000 9723815168 10020446208 804705056 8.944 100 84 168 0 832837952 7599520 20533376
05:09:52 25163 8192000000 9763647264 10071842816 839219232 9.292 100 90 180 1 873869904 13322416 21328256
05:11:26 12182 8192000000 9806974064 10129338368 873342432 9.633 100 79 158 1 918398864 26754496 18301936
05:12:43 12819 8192000000 9889992720 10224197632 932558288 10.220 100 84 168 0 998868000 48368528 17941184
05:14:02 6226 8192000000 9929436688 10273243136 964989664 10.538 100 85 167 1 1043416304 61074976 17351664
05:15:11 4839 8192000000 9966650112 10323148800 999324176 10.872 100 76 151 1 1076008128 61196320 15487632
05:16:18 2410 8192000000 9998821696 10359128064 1021477344 11.086 100 74 149 0 1103015440 66280576 15257520
05:17:22 1784 8192000000 10038895280 10407845888 1051723984 11.377 100 92 181 3 1147058960 76566768 18768208
05:18:26 1522 8192000000 10036120960 10407370752 1064327968 11.498 100 91 181 3 1139241120 56810816 18102336
05:19:28 25388 8192000000 10010625296 10404265984 1068022928 11.533 100 56 112 1 1118913392 37948448 12942016
05:20:55 9101 8192000000 9995586592 10391363584 1075042272 11.600 100 28 55 0 1107914208 26914128 5957808
05:22:04 11118 8192000000 9985980080 10389336064 1082578784 11.672 100 16 30 1 1110116896 24322848 3215264
05:23:16 10105 8192000000 9998035856 10402312192 1086936560 11.714 100 26 49 1 1112125216 19143568 6045088
05:24:27 27291 8192000000 10012888816 10403258368 1090663632 11.749 100 60 121 1 1116721168 12376848 13680688
05:25:55 4214 8192000000 10022118528 10422542336 1097967632 11.818 100 47 94 2 1140186448 31971168 10247648
05:27:00 7366 8192000000 10005014032 10413641728 1093045216 11.772 100 27 52 2 1115797024 17060672 5691136
05:28:07 5078 8192000000 9991757920 10383233024 1067958560 11.533 100 45 92 3 1103858992 25330816 10569616
05:29:17 36078 8192000000 9923777152 10312581120 948232576 10.374 90 25 49 1 983289440 30197696 4859168
05:30:54 5167 8192000000 9958565568 10331422720 1018870400 11.061 98 52 103 2 1046094048 15715296 11508352
05:32:00 8291 8192000000 9928070544 10300923904 979842144 10.683 95 45 89 3 1006610384 16792928 9975312
05:33:08 10351 8192000000 9974469632 10379829248 1044562416 11.308 100 39 74 0 1068174352 15475856 8136080
05:34:19 17076 8192000000 9960539024 10354962432 1029420352 11.163 98 24 48 1 1061940688 27530976 4989360
05:35:37 4526 8192000000 9971920048 10366320640 1061540480 11.471 100 42 84 0 1094827488 24353072 8933936
05:36:42 4345 8192000000 9980948240 10370625536 1070551808 11.557 100 51 103 3 1091034656 8758384 11724464
05:37:56 9917 8192000000 10028064480 10425245696 1098163328 11.820 100 45 91 1 1131124736 23802080 9159328
05:39:26 106937 8192000000 10088156320 10504028160 1119906768 12.026 100 33 64 0 1193660624 67305824 6448032
05:42:35 42285 8192000000 10154775376 10564370432 1138767536 12.204 100 32 64 2 1259846368 113932384 7146448
05:44:23 31388 8192000000 10176351440 10606911488 1141609872 12.231 100 48 96 0 1310936576 158444656 10882048
05:46:19 2738 8192000000 10185734864 10591158272 1153595232 12.343 100 73 143 2 1288283216 118960432 15727552
05:47:25 4147 8192000000 10204987904 10633973760 1151545408 12.324 99 59 116 0 1300133312 135615984 12971920
05:48:34 1646 8192000000 10244969712 10651168768 1177080128 12.563 100 78 152 1 1348855248 154527344 17247776
05:49:39 1126 8192000000 10265488672 10676580352 1180271520 12.593 100 83 164 2 1368715808 170126736 18317552
05:50:46 5108 8192000000 10267935216 10683789312 1169606304 12.493 99 56 112 0 1365417792 182414528 13396960
05:51:54 1874 8192000000 10332794000 10736078848 1160011600 12.403 100 86 171 0 1430943024 251426976 19504448
05:53:02 1543 8192000000 10309228000 10714112000 1166888032 12.468 100 82 164 1 1413460416 227717024 18855360
05:54:07 8983 8192000000 10270678816 10706534400 1134145248 12.160 99 44 88 0 1374703328 229286608 11271472
05:55:24 15572 8192000000 10255675104 10724151296 1141116496 12.226 99 29 58 0 1371579152 222996784 7465872
05:56:46 10240 8192000000 10213551360 10632597504 1120370976 12.030 99 29 58 0 1314193232 186670272 7151984
05:58:01 13578 8192000000 10247696944 10662977536 1089370688 11.737 97 42 84 1 1322186272 222236224 10579360
05:59:22 9754 8192000000 10244011840 10673696768 1082977520 11.676 98 28 56 0 1331542976 241720288 6845168
06:00:35 24421 8192000000 10245250048 10668584960 1097340048 11.812 99 20 40 2 1338067776 234848448 5879280
06:02:06 26136 8192000000 10205539776 10676260864 1100267808 11.840 99 24 47 0 1364357952 257903120 6187024
06:03:34 29255 8192000000 10171682336 10598453248 1114325536 11.973 100 17 32 1 1272891792 154681872 3884384
06:05:07 40878 8192000000 10123578544 10529468416 1122062608 12.046 100 47 93 0 1189239760 56641040 10536112
06:06:52 35735 8192000000 10175424448 10683772928 1126895728 12.092 100 10 20 1 1275142624 145919520 2327376
06:08:38 108747 8192000000 10068491808 10549571584 1096027776 11.800 98 30 60 1 1203342000 100333008 6981216
06:11:29 25848 8192000000 10096644416 10535309312 1132608608 12.146 100 48 96 1 1200980528 58602832 9769088
06:12:58 80330 8192000000 10130888800 10569363456 1123115984 12.056 98 25 49 1 1196227936 68019056 5092896
06:15:18 28324 8192000000 10185870096 10642096128 1154777952 12.354 100 35 70 1 1274656752 111988656 7890144
06:16:53 63803 8192000000 10186535472 10677809152 1152235968 12.330 99 22 44 0 1318676560 161044880 5395712
06:18:58 61106 8192000000 10213088912 10706108416 1141821712 12.233 98 26 53 0 1306424064 158239824 6362528
06:21:13 70622 8192000000 10192047184 10643623936 1148363648 12.294 100 23 46 1 1277488304 123715792 5408864
06:23:30 42320 8192000000 10082824960 10537787392 1145847824 12.271 100 18 36 0 1184170176 34664896 3657456
06:25:19 27433 8192000000 10100476096 10535690240 1145206432 12.264 100 48 96 0 1195564240 40609456 9748352
06:26:48 66634 8192000000 10107794192 10586869760 1154177648 12.349 100 28 56 0 1232363984 72881888 5304448
06:29:10 18246 8192000000 10129347200 10553073664 1155755552 12.363 100 40 80 1 1214745168 51220192 7769424
06:30:30 67343 8192000000 10133298960 10597007360 1125875008 12.082 97 17 34 1 1190203712 60745808 3582896
06:32:45 86221 8192000000 10089718496 10529349632 1111385408 11.946 97 27 53 1 1180270768 63278256 5607104
06:36:03 54234 8192000000 10148839424 10618937344 1150038864 12.310 100 15 30 1 1232074464 78930288 3105312
06:38:05 67896 8192000000 10113246352 10586365952 1150079840 12.310 100 17 35 0 1210190176 56879168 3231168
06:40:39 62879 8192000000 10043591824 10490855424 1056989248 11.428 93 5 10 0 1072642960 15110032 543680
06:42:42 90977 8192000000 10092014944 10588585984 1046764224 11.330 92 15 30 0 1131267616 81009696 3493696
06:45:23 49865 8192000000 10098758816 10531606528 1091071776 11.753 97 35 70 1 1155619472 56892848 7654848
06:47:31 105444 8192000000 10067645904 10525696000 1113669456 11.967 99 15 30 1 1172154128 55099088 3385584
06:50:16 34971 8192000000 10103826400 10549805056 1117605696 12.004 99 42 84 1 1194248672 68147776 8495200
06:51:56 68185 8192000000 10089935152 10605797376 1097615600 11.815 97 4 8 0 1183137568 84763152 758816
06:55:18 91470 8192000000 10013733136 10443153408 1058780448 11.445 94 23 46 1 1095217024 31700160 4736416
06:57:50 85941 8192000000 10082977264 10535600128 1096487584 11.804 98 17 34 0 1126163072 26221904 3453584
07:00:16 57298 8192000000 10070998624 10533646336 1101481184 11.852 98 14 28 0 1157623952 53164016 2978752
07:02:15 86837 8192000000 10081991520 10576814080 1111134816 11.943 99 14 28 1 1180756496 66595952 3025728
07:05:10 40110 8192000000 10117657920 10553368576 1136147296 12.179 100 25 50 0 1206316688 64697632 5471760
07:06:54 54550 8192000000 10146994848 10584207360 1128641456 12.109 99 22 44 0 1231326560 97838032 4847072
07:08:53 84240 8192000000 10116313408 10632650752 1142554672 12.240 100 19 38 1 1270958720 124419600 3984448
07:11:30 25414 8192000000 10080988544 10514022400 1142632640 12.240 100 27 54 0 1179469616 31504672 5332304
07:12:56 92317 8192000000 10092642272 10558267392 1129384912 12.116 98 15 30 0 1205352144 73154064 2813168
07:15:30 71184 8192000000 10117628880 10573295616 1129910176 12.121 98 38 76 2 1184624096 46886576 7827344
07:18:19 47025 8192000000 10105612752 10590429184 1151417696 12.323 100 24 48 0 1202032256 45470128 5144432
07:20:07 53206 8192000000 10100659376 10565357568 1132042096 12.141 98 7 14 0 1184218720 50735104 1441520
07:22:00 57270 8192000000 10080489840 10532872192 1153879392 12.346 100 18 36 0 1202634672 44907488 3847792
07:24:00 81408 8192000000 10090055376 10563649536 1128140448 12.104 99 10 20 0 1198064320 67750064 2173808
07:26:22 77363 8192000000 10097759040 10565152768 1097069856 11.810 96 22 44 2 1152970688 50954864 4945968
07:28:53 44052 8192000000 10109721568 10551336960 1104978832 11.885 97 28 56 0 1172813392 62317792 5516768
07:30:49 81244 8192000000 10166035856 10644307968 1117500144 12.003 98 18 36 0 1214654160 93420128 3733888
07:33:11 38520 8192000000 10104887120 10544607232 1120284848 12.030 99 33 66 0 1162591968 35941040 6366080
07:34:54 126089 8192000000 10083970256 10524749824 1059446000 11.451 94 40 80 1 1139059760 71433104 8180656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 30-12-2021 07:39:37 to 30-12-2021 07:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 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 30-12-2021 07:39:52 to 30-12-2021 07:39:54.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          228895
MON$OLDEST_ACTIVE               231775
MON$OLDEST_SNAPSHOT             231775
MON$NEXT_TRANSACTION            231775
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-30 07:30:08.6030 GMT
MON$PAGES                       2040928
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2697 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2697 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2697 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:102: 
:103: mscs-test-161.budzdorov.ru    Thu Dec 30 07:37:23 2021
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
:106: 
:107: mscs-test-161.budzdorov.ru    Thu Dec 30 07:37:33 2021
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:109: 
:110: 
:111: mscs-test-161.budzdorov.ru    Thu Dec 30 07:37:34 2021
:112:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:113: 
:114: 
:115: mscs-test-161.budzdorov.ru    Thu Dec 30 07:37:34 2021
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:117: 
--- end of diff output ---

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

30.12.21 07:39:55. There are 3 logs in /var/tmp/logs-oltp40 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-12-30 07:39:58 No severe PSQL-related problems occured
30.12.21 07:39:59. End of report.