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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16080068 kB
MemAvailable 31004488 kB
Buffers 0 kB
Cached 15648368 kB
SwapCached 1868 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-08-24 10:37:06
DTS_END                         2021-08-24 10:37:06.6650
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/33928
TRN_ID                          241703
ATT_ID                          687
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: 9248.65 27872 1109838
customer order: creation 168.40 406 20208
customer order: refuse 15.27 326 1832
order to supplier: creation 46.33 1396 5559
order to supplier: removal 3.51 117337 421
invoice (draft): creation 46.40 3491 5568
invoice (draft): removal 52.50 967 6300
invoice accept: apply 42.59 20515 5111
invoice accept: cancel 47.48 882 5697
customer reserve: creation 3922.85 203 470742
customer reserve: removal 4603.57 69 552428
realization accept: apply 83.53 211 10024
realization accept: cancel 86.46 130 10375
payment from customer: creation 59.51 152 7141
payment from customer: removal 5.87 113 704
payment to supplier: creation 49.52 170 5942
payment to supplier: removal 3.56 87 427
service: total inventory turnovers 4.00 3441 480
service: total monetary turnovers 6.35 514 762
service: aggregate perf. data 0.93 17671 112
service: refresh index statistics 0.04 389357 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-08-2021 10:40:11 to 24-08-2021 10:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5745
TEST_TIME 2 4902
TEST_TIME 3 4496
TEST_TIME 4 5396
TEST_TIME 5 5367
TEST_TIME 6 5657
TEST_TIME 7 5603
TEST_TIME 8 5524
TEST_TIME 9 5799
TEST_TIME 10 5788
TEST_TIME 11 6573
TEST_TIME 12 6663
TEST_TIME 13 7106
TEST_TIME 14 7208
TEST_TIME 15 7596
TEST_TIME 16 7945
TEST_TIME 17 7931
TEST_TIME 18 8032
TEST_TIME 19 8398
TEST_TIME 20 8305
TEST_TIME 21 8582
TEST_TIME 22 9041
TEST_TIME 23 9610
TEST_TIME 24 9741
TEST_TIME 25 9622
TEST_TIME 26 9534
TEST_TIME 27 11145
TEST_TIME 28 11284
TEST_TIME 29 11127
TEST_TIME 30 10955
TEST_TIME 31 10901
TEST_TIME 32 10888
TEST_TIME 33 10724
TEST_TIME 34 10639
TEST_TIME 35 10396
TEST_TIME 36 10408
TEST_TIME 37 10266
TEST_TIME 38 10221
TEST_TIME 39 10122
TEST_TIME 40 10161
TEST_TIME 41 10105
TEST_TIME 42 10011
TEST_TIME 43 9963
TEST_TIME 44 9839
TEST_TIME 45 9832
TEST_TIME 46 9997
TEST_TIME 47 9916
TEST_TIME 48 9919
TEST_TIME 49 9987
TEST_TIME 50 10060
TEST_TIME 51 10054
TEST_TIME 52 10159
TEST_TIME 53 10157
TEST_TIME 54 10131
TEST_TIME 55 10103
TEST_TIME 56 10159
TEST_TIME 57 10198
TEST_TIME 58 10097
TEST_TIME 59 9994
TEST_TIME 60 9949
TEST_TIME 61 9884
TEST_TIME 62 9840
TEST_TIME 63 9740
TEST_TIME 64 9707
TEST_TIME 65 9605
TEST_TIME 66 9666
TEST_TIME 67 9716
TEST_TIME 68 9689
TEST_TIME 69 9660
TEST_TIME 70 9655
TEST_TIME 71 9595
TEST_TIME 72 9577
TEST_TIME 73 9639
TEST_TIME 74 9726
TEST_TIME 75 9789
TEST_TIME 76 9774
TEST_TIME 77 9758
TEST_TIME 78 9688
TEST_TIME 79 9612
TEST_TIME 80 9539
TEST_TIME 81 9526
TEST_TIME 82 9563
TEST_TIME 83 9500
TEST_TIME 84 9468
TEST_TIME 85 9435
TEST_TIME 86 9437
TEST_TIME 87 9387
TEST_TIME 88 9375
TEST_TIME 89 9414
TEST_TIME 90 9524
TEST_TIME 91 9498
TEST_TIME 92 9545
TEST_TIME 93 9564
TEST_TIME 94 9639
TEST_TIME 95 9618
TEST_TIME 96 9604
TEST_TIME 97 9541
TEST_TIME 98 9517
TEST_TIME 99 9497
TEST_TIME 100 9456
TEST_TIME 101 9406
TEST_TIME 102 9361
TEST_TIME 103 9292
TEST_TIME 104 9287
TEST_TIME 105 9258
TEST_TIME 106 9273
TEST_TIME 107 9260
TEST_TIME 108 9256
TEST_TIME 109 9298
TEST_TIME 110 9340
TEST_TIME 111 9328
TEST_TIME 112 9308
TEST_TIME 113 9342
TEST_TIME 114 9352
TEST_TIME 115 9330
TEST_TIME 116 9380
TEST_TIME 117 9402
TEST_TIME 118 9379
TEST_TIME 119 9329
TEST_TIME 120 9251

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-08-2021 10:40:15 to 24-08-2021 10:40:18.

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 1545164 1545164 0 0.00 0 27347 8 0 0
doc_list_biud 1545137 1545137 0 0.00 0 69926 31 0 0
sp_add_invoice_to_stock 5233 5111 122 2.33 39 197759 20516 0 122
sp_cancel_adding_invoice 5697 5697 0 0.00 4 57977 882 0 0
sp_cancel_client_order 1832 1832 0 0.00 5 16794 327 0 0
sp_cancel_customer_reserve 552428 552428 0 0.00 1 22492 70 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 5923 113 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 6393 88 0 0
sp_cancel_supplier_invoice 6310 6300 10 0.15 2 69933 967 0 10
sp_cancel_supplier_order 421 421 0 0.00 5 1218911 117338 0 0
sp_cancel_write_off 10375 10375 0 0.00 1 14992 130 0 0
sp_client_order 20208 20208 0 0.00 4 40809 407 0 0
sp_customer_reserve 470742 470742 0 0.00 0 95090 204 0 0
sp_fill_shopping_cart 31314 31314 0 0.00 1 12290 43 0 0
sp_fill_shopping_cart_clo_res 470730 470730 0 0.00 0 20937 16 0 0
sp_get_clo_for_invoice 5095 5095 0 0.00 0 20838 294 0 0
sp_kill_cost_storno 17203 17203 0 0.00 0 5999 59 0 0
sp_kill_qstorno_ret_qs2qd 1121952 1121952 0 0.00 0 69925 38 0 0
sp_kill_qty_storno 592171 592171 0 0.00 0 69926 80 0 0
sp_lock_selected_doc 21056 21056 0 0.00 0 14235 8 0 0
sp_make_cost_storno 28202 28202 0 0.00 0 26692 76 0 0
sp_multiply_rows_for_pdistr 28201 28201 0 0.00 0 7171 31 0 0
sp_multiply_rows_for_qdistr 20208 20208 0 0.00 0 17437 194 0 0
sp_pay_from_customer 7141 7141 0 0.00 4 26813 153 0 0
sp_pay_to_supplier 5942 5942 0 0.00 5 27754 171 0 0
sp_payment_common 13078 13078 0 0.00 1 23965 31 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 0 16787 297 0 0
sp_qd_handle_on_invoice_upd_sts 10791 10791 0 0.00 0 47675 361 0 0
sp_qd_handle_on_reserve_upd_sts 10024 10024 0 0.00 0 21791 49 0 0
sp_reserve_write_off 10024 10024 0 0.00 6 27377 211 0 0
sp_supplier_invoice 5568 5568 0 0.00 9 104567 3491 0 0
sp_supplier_order 5559 5559 0 0.00 5 82380 1396 0 0
srv_aggregate_perf_data 112 112 0 0.00 2813 340763 17672 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1677 129627 43775 0 0
srv_find_qd_qs_mism 1061566 1061566 0 0.00 0 14588 7 0 0
srv_make_invnt_saldo 480 480 0 0.00 42 99498 3442 0 0
srv_make_money_saldo 762 762 0 0.00 1 19955 514 0 0
srv_recalc_idx_stat 5 5 0 0.00 166156 580412 389357 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5111 5111 0 0.00 0 9117 178 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 9997 142 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 4966 15 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 2518 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 309 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 1437 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 593 15 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 22054 1771 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 471 4 0 0
t$perf-norm:sp_client_order 20209 20209 0 0.00 0 19187 18 0 0
t$perf-norm:sp_customer_reserve 1091 1091 0 0.00 0 7774 15 0 0
t$perf-norm:sp_pay_from_customer 7141 7141 0 0.00 0 5096 10 0 0
t$perf-norm:sp_pay_to_supplier 5942 5942 0 0.00 0 5549 9 0 0
t$perf-norm:sp_reserve_write_off 10025 10025 0 0.00 0 9980 14 0 0
t$perf-norm:sp_supplier_invoice 5568 5568 0 0.00 0 4878 16 0 0
t$perf-norm:sp_supplier_order 5559 5559 0 0.00 0 9018 19 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 675 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 492 17 0 0
t$perf-norm:srv_make_invnt_saldo 480 480 0 0.00 0 676 10 0 0
t$perf-norm:srv_make_money_saldo 762 762 0 0.00 0 2645 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 76 17 0 0
v_add_invoice_to_stock 10432 10188 244 2.33 0 15332 22 0 244
v_all_customers 37056 37056 0 0.00 0 35 1 0 0
v_all_suppliers 30960 30960 0 0.00 0 87 1 0 0
v_all_wares 40408 40408 0 0.00 0 172 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 18935 30 0 0
v_cancel_client_order 3664 3664 0 0.00 0 3454 7 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 3782 14 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 1724 6 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1086 10 0 0
v_cancel_supplier_invoice 876 856 20 2.28 0 1883 16 0 20
v_cancel_supplier_order 842 842 0 0.00 0 3566 17 0 0
v_cancel_write_off 2042 2042 0 0.00 0 2717 18 0 0
v_max_id_clo_ord 5550 5550 0 0.00 0 173 1 0 0
v_max_id_clo_res 1091 1091 0 0.00 0 2397 9 0 0
v_max_id_ord_sup 5560 5560 0 0.00 0 5629 4 0 0
v_max_non_paid_invoice 1570 1570 0 0.00 0 3174 5 0 0
v_max_non_paid_realizn 3883 3883 0 0.00 0 21743 9 0 0
v_min_id_clo_ord 5550 5550 0 0.00 0 3558 8 0 0
v_min_id_clo_res 1091 1091 0 0.00 0 5418 22 0 0
v_min_id_ord_sup 5560 5560 0 0.00 0 4737 6 0 0
v_min_non_paid_invoice 5940 5940 0 0.00 0 7040 18 0 0
v_min_non_paid_realizn 7138 7138 0 0.00 0 7932 21 0 0
v_our_firm 9862 9862 0 0.00 0 19 1 0 0
v_reserve_write_off 20045 20045 0 0.00 0 15338 10 0 0
x_lock_depdocs_on_canc_invoice 11569 11569 0 0.00 0 14039 129 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 13017 390 0 0
x_make_qstorno_1000_1200 5559 5559 0 0.00 1 82364 1314 0 0
x_make_qstorno_1200_2000 5568 5568 0 0.00 5 104525 3335 0 0
x_make_qstorno_2100_3300 470742 470742 0 0.00 0 95088 185 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-08-2021 10:40:19 to 24-08-2021 10:40:24.

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
08:07:09 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 24090 8192000000 9718759552 10011938816 791130944 8.806 100 90 180 0 820989968 7954544 21904480
08:09:36 23540 8192000000 9743529280 10045972480 820630816 9.105 100 91 180 1 854486000 12318320 21536864
08:11:04 11880 8192000000 9788718160 10100195328 850368128 9.404 100 91 182 1 892444176 20710752 21365296
08:12:30 11124 8192000000 9853699792 10187722752 906786064 9.966 100 83 166 0 969394224 44123840 18484320
08:13:44 13560 8192000000 9901170816 10246438912 951703408 10.408 100 75 150 1 1011880672 44271392 15905872
08:15:00 4485 8192000000 9963619408 10314555392 988815520 10.770 100 87 171 2 1079162160 72322704 18023936
08:16:08 3595 8192000000 9996750672 10355204096 1018465968 11.057 100 89 176 0 1107834848 71674816 17694064
08:17:13 2272 8192000000 10026368400 10393276416 1042578000 11.289 100 93 185 0 1139362512 77409024 19375488
08:18:16 1900 8192000000 10034204864 10401464320 1059003136 11.447 100 96 195 0 1141885120 62856032 20025952
08:19:19 5385 8192000000 10024295664 10404438016 1059892000 11.455 100 49 98 1 1124325584 54893104 9540480
08:20:25 9449 8192000000 9986521776 10377936896 1066563152 11.519 100 21 42 0 1109809952 38985104 4261696
08:21:35 8531 8192000000 9988401216 10393837568 1073918096 11.589 100 32 64 2 1104794672 24114672 6761904
08:22:44 56853 8192000000 10009922800 10412244992 1082448288 11.671 100 44 88 0 1107195616 15127376 9619952
08:24:45 6247 8192000000 10013231456 10412081152 1090470416 11.747 100 38 76 1 1129482704 30159696 8852592
08:25:54 5104 8192000000 10027043872 10427731968 1088161648 11.725 99 49 100 1 1127072848 27158032 11753168
08:27:02 9961 8192000000 10014385792 10419515392 1080304256 11.650 98 27 52 2 1111000752 25139872 5556624
08:28:13 27272 8192000000 10000053408 10408001536 1053302720 11.392 97 32 64 0 1096899792 36674640 6922432
08:29:42 6030 8192000000 9961514240 10339213312 1021006992 11.082 96 36 69 1 1051847664 23489120 7351552
08:30:48 9410 8192000000 9959681328 10362146816 1040497712 11.269 100 31 63 1 1079018880 31443536 7077632
08:32:00 11668 8192000000 9955903744 10357559296 1041768048 11.282 99 16 32 1 1071552880 26355936 3428896
08:33:13 28102 8192000000 9967999712 10369466368 1052761232 11.387 100 32 63 2 1079818528 20524160 6533136
08:34:45 5497 8192000000 9995370864 10392522752 1076530800 11.614 100 50 101 0 1115736720 27274464 11931456
08:35:52 7892 8192000000 9999741168 10410061824 1078049712 11.629 100 49 98 1 1107260432 17624016 11586704
08:37:00 11196 8192000000 10008861200 10423664640 1082876976 11.675 100 33 62 0 1111766448 22243472 6646000
08:38:16 45302 8192000000 10028212736 10437136384 1099502096 11.833 100 41 80 3 1139226112 31430096 8293920
08:40:06 36989 8192000000 10085692592 10492379136 1113235728 11.963 100 52 103 1 1191776880 67680912 10860240
08:41:48 10838 8192000000 10109330016 10520576000 1135059680 12.169 100 58 116 0 1213714032 66332688 12321664
08:43:03 15959 8192000000 10127443792 10544418816 1143648848 12.250 100 46 93 0 1241736080 86974352 11112880
08:44:28 13119 8192000000 10181566448 10597666816 1142617840 12.240 100 58 114 2 1284640928 129477792 12545296
08:45:48 5892 8192000000 10223602336 10640068608 1150098048 12.310 100 48 96 1 1324538624 163865200 10575376
08:46:59 23314 8192000000 10218830528 10645131264 1150986352 12.319 99 43 85 0 1318754976 157214304 10554320
08:48:24 2696 8192000000 10277507280 10694283264 1181524944 12.604 100 81 162 0 1382468512 183355504 17588064
08:49:33 3223 8192000000 10297962704 10707054592 1166396832 12.463 99 76 151 1 1385818240 202962288 16459120
08:50:43 3199 8192000000 10262460416 10684833792 1157057136 12.376 99 81 162 0 1353510496 178183376 18269984
08:51:53 2723 8192000000 10322585888 10724900864 1162800000 12.429 100 89 177 0 1417900592 235584720 19515872
08:53:02 2894 8192000000 10278706224 10692497408 1135311648 12.171 99 78 154 2 1363255824 210895040 17049136
08:54:12 1762 8192000000 10328829952 10726600704 1158471152 12.389 100 94 188 0 1423155248 243528496 21155600
08:55:18 1675 8192000000 10375987264 10779385856 1158287824 12.387 100 74 147 2 1470655520 295099136 17268560
08:56:25 8273 8192000000 10383675968 10823778304 1158149376 12.386 100 49 98 2 1486839376 316637728 12052272
08:57:40 6950 8192000000 10413280592 10825183232 1152531376 12.333 100 72 142 2 1504170736 334983056 16656304
08:58:53 5667 8192000000 10460575984 10872721408 1165461360 12.454 100 70 137 1 1547722736 365560992 16700384
09:00:03 19420 8192000000 10395724592 10845134848 1156400448 12.370 100 40 80 0 1512274672 345102432 10771792
09:01:28 19633 8192000000 10392624944 10873061376 1149305984 12.303 100 37 74 1 1498429152 339632608 9490560
09:02:56 18361 8192000000 10394412656 10863570944 1127032720 12.093 100 28 55 2 1489517104 355302848 7181536
09:04:20 34118 8192000000 10110583952 10662617088 1100822912 11.845 98 18 36 4 1360663936 255302624 4538400
09:05:58 52455 8192000000 10100810736 10538520576 1109892272 11.931 98 34 67 0 1150797328 34366992 6538064
09:07:53 56897 8192000000 10128179680 10558640128 1131438304 12.135 100 23 47 0 1206741488 69867264 5435920
09:09:53 107574 8192000000 10085857568 10542350336 1123538496 12.060 99 30 59 1 1189827792 58885280 7404016
09:12:41 41041 8192000000 10084318160 10552229888 1116918192 11.998 98 27 54 1 1180842816 58740992 5183632
09:14:34 43373 8192000000 10143386384 10592964608 1129083600 12.113 99 39 78 0 1209114112 70484064 9546448
09:16:25 59877 8192000000 10121200640 10616397824 1130503312 12.126 99 17 34 0 1258645712 124098768 4043632
09:18:27 21282 8192000000 10132601120 10608091136 1138971936 12.206 100 12 24 0 1241583968 100016240 2595792
09:19:50 68246 8192000000 10116596480 10595135488 1139440016 12.210 100 16 32 0 1237862272 94899424 3522832
09:22:06 70557 8192000000 10074211360 10566680576 1144254224 12.256 100 14 28 1 1231803584 84339648 3209712
09:24:20 87001 8192000000 10148245184 10617503744 1142702736 12.241 99 28 56 1 1230406784 81725856 5978192
09:26:52 61834 8192000000 10092947888 10532761600 1104760304 11.883 96 19 38 0 1161813904 53101184 3952416
09:29:05 25935 8192000000 10111817616 10590781440 1149190576 12.302 100 24 48 0 1217844480 63521808 5132096
09:31:31 24328 8192000000 10080963440 10516877312 1132317984 12.143 100 54 105 0 1178648944 35429696 10901264
09:33:02 61939 8192000000 10116677904 10603077632 1112080768 11.952 98 17 34 0 1164297424 48981696 3234960
09:35:04 30816 8192000000 10071597200 10490359808 1087611568 11.720 96 45 90 2 1127717968 30837824 9268576
09:36:35 79762 8192000000 10082496064 10553643008 1132391312 12.144 100 30 60 0 1197906832 59376288 6139232
09:39:02 41265 8192000000 10101900560 10576441344 1132735344 12.147 100 19 38 2 1200300528 63858672 3706512
09:40:49 45507 8192000000 10111404144 10582073344 1128148784 12.104 99 14 28 0 1210282736 78787248 3346704
09:42:35 74390 8192000000 10110890256 10616549376 1132236512 12.142 99 17 34 0 1236334960 99771168 4327280
09:45:07 51454 8192000000 10063841808 10520973312 1092809440 11.769 96 15 30 1 1123982672 28276240 2896992
09:46:59 34663 8192000000 10083657216 10526248960 1118788192 12.016 99 38 76 1 1172381456 45836960 7756304
09:48:38 47131 8192000000 10135117200 10593976320 1140922928 12.224 100 21 42 0 1235508800 89428544 5157328
09:50:34 47037 8192000000 10124030080 10636455936 1118002144 12.008 98 15 30 0 1224730448 103267232 3461072
09:52:22 46067 8192000000 10060780336 10515161088 1104164512 11.877 98 20 40 3 1153134704 44794496 4175696
09:54:09 16588 8192000000 10051228992 10475208704 1087948640 11.723 97 36 72 1 1125402320 30564464 6889216
09:55:26 93834 8192000000 10068443440 10498248704 1108910288 11.922 98 40 81 1 1154164400 37392976 7861136
09:58:11 25155 8192000000 10134412272 10578436096 1127516928 12.098 99 34 68 0 1230868240 96741200 6610112
09:59:37 34155 8192000000 10057668160 10543935488 1111367808 11.945 98 7 14 0 1165345536 52362528 1615200
10:01:11 47613 8192000000 10060678384 10527596544 1125014848 12.074 99 12 24 0 1160050160 32610176 2425136
10:02:59 50909 8192000000 10091044880 10535161856 1104896944 11.884 97 23 46 1 1148073968 37866000 5311024
10:05:00 36010 8192000000 10166866736 10611130368 1148219280 12.293 100 33 66 1 1253055776 97924112 6912384
10:06:45 75805 8192000000 10096129600 10608013312 1151501728 12.324 100 17 34 1 1261158320 105274320 4382272
10:09:32 67998 8192000000 10121729952 10608152576 1160994864 12.413 100 34 68 3 1232860688 64890448 6975376
10:12:20 44459 8192000000 10106077424 10563231744 1157529792 12.380 100 15 30 0 1206167744 45705680 2932272
10:14:06 119515 8192000000 10100860976 10582818816 1157037728 12.376 100 33 66 1 1219473632 55867216 6568688
10:17:44 43330 8192000000 10072103680 10550190080 1143650736 12.250 99 5 8 1 1176557488 32167600 739152
10:19:28 77200 8192000000 10088672512 10556637184 1127649440 12.099 98 12 24 1 1177926608 47776576 2500592
10:21:46 99077 8192000000 10106049216 10550820864 1064054864 11.495 93 13 25 0 1136540832 69743120 2742848
10:24:47 64233 8192000000 10126927488 10584911872 1102573440 11.862 96 27 52 1 1195356944 87442176 5341328
10:26:51 129627 8192000000 10068335680 10519420928 1060179120 11.458 93 15 30 1 1100175024 36625056 3370848
10:30:02 66678 8192000000 10084508752 10572644352 1109346608 11.926 98 20 40 0 1174658736 61371904 3940224
10:32:09 23250 8192000000 10079544592 10517385216 1110000160 11.932 98 41 78 2 1162313184 44429152 7883872
10:33:36 80080 8192000000 10089253408 10560917504 1123971760 12.064 99 18 36 0 1168078784 40170528 3936496
10:36:00 119905 8192000000 9007290592 9250205696 163759136 1.959 15 5 10 0 179443696 14273232 1411328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-08-2021 10:40:25 to 24-08-2021 10:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 24-08-2021 10:40:42 to 24-08-2021 10:40:43.

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          242544
MON$OLDEST_ACTIVE               242545
MON$OLDEST_SNAPSHOT             242545
MON$NEXT_TRANSACTION            242545
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                       2052064
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             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 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 ---
:219: 
:220: mscs-test-161.budzdorov.ru    Tue Aug 24 10:38:06 2021
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Tue Aug 24 10:38:06 2021
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:226: 
--- end of diff output ---

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

24.08.21 10:40:45. 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-08-24 10:40:47 No severe PSQL-related problems occured
24.08.21 10:40:48. End of report.