Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.08.2021 01: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 16111436 kB
MemAvailable 30988840 kB
Buffers 0 kB
Cached 15594644 kB
SwapCached 3644 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-08-23 01:37:45
DTS_END                         2021-08-23 01:37:45.3000
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/34460
TRN_ID                          243724
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: 9587.09 16437 1150451
customer order: creation 174.55 347 20946
customer order: refuse 14.75 379 1770
order to supplier: creation 47.00 1361 5640
order to supplier: removal 3.48 110235 417
invoice (draft): creation 46.25 3657 5550
invoice (draft): removal 54.60 854 6552
invoice accept: apply 43.06 17839 5167
invoice accept: cancel 49.55 714 5946
customer reserve: creation 4018.74 175 482249
customer reserve: removal 4833.36 61 580003
realization accept: apply 85.08 158 10209
realization accept: cancel 83.86 109 10063
payment from customer: creation 60.76 122 7291
payment from customer: removal 5.61 82 673
payment to supplier: creation 49.69 127 5963
payment to supplier: removal 3.63 65 436
service: total inventory turnovers 5.07 1128 608
service: total monetary turnovers 7.14 26 857
service: aggregate perf. data 0.89 16750 107
service: refresh index statistics 0.03 174556 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-08-2021 01:39:32 to 23-08-2021 01:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2834
TEST_TIME 2 3804
TEST_TIME 3 5012
TEST_TIME 4 5334
TEST_TIME 5 5192
TEST_TIME 6 5168
TEST_TIME 7 5214
TEST_TIME 8 5085
TEST_TIME 9 5292
TEST_TIME 10 5474
TEST_TIME 11 5599
TEST_TIME 12 5718
TEST_TIME 13 5557
TEST_TIME 14 5543
TEST_TIME 15 5636
TEST_TIME 16 6002
TEST_TIME 17 6241
TEST_TIME 18 7004
TEST_TIME 19 7128
TEST_TIME 20 7680
TEST_TIME 21 7978
TEST_TIME 22 8024
TEST_TIME 23 8364
TEST_TIME 24 8660
TEST_TIME 25 8821
TEST_TIME 26 9099
TEST_TIME 27 9448
TEST_TIME 28 9589
TEST_TIME 29 10299
TEST_TIME 30 11055
TEST_TIME 31 11273
TEST_TIME 32 11270
TEST_TIME 33 11203
TEST_TIME 34 11059
TEST_TIME 35 10959
TEST_TIME 36 10860
TEST_TIME 37 10684
TEST_TIME 38 10460
TEST_TIME 39 10492
TEST_TIME 40 10319
TEST_TIME 41 10297
TEST_TIME 42 10234
TEST_TIME 43 10222
TEST_TIME 44 10202
TEST_TIME 45 10114
TEST_TIME 46 10023
TEST_TIME 47 9999
TEST_TIME 48 10109
TEST_TIME 49 10003
TEST_TIME 50 9906
TEST_TIME 51 9842
TEST_TIME 52 9788
TEST_TIME 53 9738
TEST_TIME 54 9629
TEST_TIME 55 9631
TEST_TIME 56 9563
TEST_TIME 57 9640
TEST_TIME 58 9654
TEST_TIME 59 9742
TEST_TIME 60 9736
TEST_TIME 61 9817
TEST_TIME 62 9814
TEST_TIME 63 9874
TEST_TIME 64 9829
TEST_TIME 65 9865
TEST_TIME 66 9957
TEST_TIME 67 9924
TEST_TIME 68 10000
TEST_TIME 69 10056
TEST_TIME 70 10041
TEST_TIME 71 9920
TEST_TIME 72 9928
TEST_TIME 73 9949
TEST_TIME 74 9905
TEST_TIME 75 9872
TEST_TIME 76 9816
TEST_TIME 77 9735
TEST_TIME 78 9681
TEST_TIME 79 9617
TEST_TIME 80 9567
TEST_TIME 81 9613
TEST_TIME 82 9616
TEST_TIME 83 9588
TEST_TIME 84 9583
TEST_TIME 85 9656
TEST_TIME 86 9622
TEST_TIME 87 9639
TEST_TIME 88 9711
TEST_TIME 89 9749
TEST_TIME 90 9768
TEST_TIME 91 9761
TEST_TIME 92 9765
TEST_TIME 93 9805
TEST_TIME 94 9842
TEST_TIME 95 9745
TEST_TIME 96 9680
TEST_TIME 97 9704
TEST_TIME 98 9654
TEST_TIME 99 9622
TEST_TIME 100 9630
TEST_TIME 101 9624
TEST_TIME 102 9623
TEST_TIME 103 9577
TEST_TIME 104 9548
TEST_TIME 105 9684
TEST_TIME 106 9641
TEST_TIME 107 9637
TEST_TIME 108 9673
TEST_TIME 109 9658
TEST_TIME 110 9687
TEST_TIME 111 9677
TEST_TIME 112 9714
TEST_TIME 113 9756
TEST_TIME 114 9725
TEST_TIME 115 9691
TEST_TIME 116 9700
TEST_TIME 117 9661
TEST_TIME 118 9652
TEST_TIME 119 9617
TEST_TIME 120 9588

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-08-2021 01:39:36 to 23-08-2021 01:39:39.

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 1595667 1595667 0 0.00 0 12480 6 0 0
doc_list_biud 1595667 1595667 0 0.00 0 35017 28 0 0
sp_add_invoice_to_stock 5327 5167 160 3.00 21 194427 17840 0 160
sp_cancel_adding_invoice 5946 5946 0 0.00 4 39656 714 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 15973 379 0 0
sp_cancel_customer_reserve 580003 580003 0 0.00 1 29994 62 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 4678 83 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 1855 65 0 0
sp_cancel_supplier_invoice 6569 6552 17 0.25 2 29146 854 0 17
sp_cancel_supplier_order 417 417 0 0.00 8 1091112 110235 0 0
sp_cancel_write_off 10063 10063 0 0.00 1 12456 109 0 0
sp_client_order 20946 20946 0 0.00 3 26052 347 0 0
sp_customer_reserve 482249 482249 0 0.00 0 68732 175 0 0
sp_fill_shopping_cart 32128 32128 0 0.00 1 14776 38 0 0
sp_fill_shopping_cart_clo_res 482246 482246 0 0.00 0 19067 13 0 0
sp_get_clo_for_invoice 5161 5161 0 0.00 0 17143 269 0 0
sp_kill_cost_storno 17118 17118 0 0.00 0 10714 54 0 0
sp_kill_qstorno_ret_qs2qd 1177038 1177038 0 0.00 0 29990 33 0 0
sp_kill_qty_storno 620118 620118 0 0.00 0 35016 72 0 0
sp_lock_selected_doc 21143 21143 0 0.00 0 9495 5 0 0
sp_make_cost_storno 28621 28621 0 0.00 0 11150 50 0 0
sp_multiply_rows_for_pdistr 28621 28621 0 0.00 0 12475 29 0 0
sp_multiply_rows_for_qdistr 20946 20946 0 0.00 0 12680 156 0 0
sp_pay_from_customer 7291 7291 0 0.00 4 16844 122 0 0
sp_pay_to_supplier 5963 5963 0 0.00 5 10965 127 0 0
sp_payment_common 13254 13254 0 0.00 1 16806 25 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 15966 340 0 0
sp_qd_handle_on_invoice_upd_sts 11104 11104 0 0.00 0 35014 329 0 0
sp_qd_handle_on_reserve_upd_sts 10209 10209 0 0.00 0 14525 45 0 0
sp_reserve_write_off 10209 10209 0 0.00 7 15600 158 0 0
sp_supplier_invoice 5550 5550 0 0.00 6 99920 3657 0 0
sp_supplier_order 5640 5640 0 0.00 5 69447 1361 0 0
srv_aggregate_perf_data 107 107 0 0.00 3193 152093 16751 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2253 121254 42922 0 0
srv_find_qd_qs_mism 1100715 1100715 0 0.00 0 9862 5 0 0
srv_make_invnt_saldo 608 608 0 0.00 28 83143 1129 0 0
srv_make_money_saldo 857 857 0 0.00 0 1810 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 99412 284846 174557 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5167 5167 0 0.00 0 11450 172 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 6124 129 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 4152 12 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 782 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2778 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 4138 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 873 18 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 23026 1611 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 2670 9 0 0
t$perf-norm:sp_client_order 20946 20946 0 0.00 0 8409 21 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 2620 8 0 0
t$perf-norm:sp_pay_from_customer 7291 7291 0 0.00 0 6166 12 0 0
t$perf-norm:sp_pay_to_supplier 5963 5963 0 0.00 0 6387 14 0 0
t$perf-norm:sp_reserve_write_off 10209 10209 0 0.00 0 7763 16 0 0
t$perf-norm:sp_supplier_invoice 5550 5550 0 0.00 0 11218 28 0 0
t$perf-norm:sp_supplier_order 5640 5640 0 0.00 0 8188 19 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 799 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 685 13 0 0
t$perf-norm:srv_make_invnt_saldo 608 608 0 0.00 0 2064 19 0 0
t$perf-norm:srv_make_money_saldo 857 857 0 0.00 0 1926 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 22 7 0 0
v_add_invoice_to_stock 10636 10316 320 3.00 0 7875 23 0 320
v_all_customers 38236 38236 0 0.00 0 141 1 0 0
v_all_suppliers 30874 30874 0 0.00 0 29 1 0 0
v_all_wares 41892 41892 0 0.00 0 185 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 772 7 0 0
v_cancel_client_order 3540 3540 0 0.00 0 6050 10 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 4604 8 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 4534 10 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 1824 6 0 0
v_cancel_supplier_invoice 824 790 34 4.12 0 1051 16 0 34
v_cancel_supplier_order 834 834 0 0.00 0 497 4 0 0
v_cancel_write_off 2034 2034 0 0.00 0 4264 17 0 0
v_max_id_clo_ord 5638 5638 0 0.00 0 1054 2 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 2909 14 0 0
v_max_id_ord_sup 5544 5544 0 0.00 0 5728 3 0 0
v_max_non_paid_invoice 1704 1704 0 0.00 0 2277 4 0 0
v_max_non_paid_realizn 3889 3889 0 0.00 0 512 3 0 0
v_min_id_clo_ord 5638 5638 0 0.00 0 1433 4 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 2666 7 0 0
v_min_id_ord_sup 5544 5544 0 0.00 0 5335 8 0 0
v_min_non_paid_invoice 5963 5963 0 0.00 0 6945 12 0 0
v_min_non_paid_realizn 7291 7291 0 0.00 0 10884 17 0 0
v_our_firm 10460 10460 0 0.00 0 14 1 0 0
v_reserve_write_off 20418 20418 0 0.00 0 6776 8 0 0
x_lock_depdocs_on_canc_invoice 12103 12103 0 0.00 0 13084 105 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 6095 331 0 0
x_make_qstorno_1000_1200 5640 5640 0 0.00 2 69438 1282 0 0
x_make_qstorno_1200_2000 5550 5550 0 0.00 2 99603 3522 0 0
x_make_qstorno_2100_3300 482249 482249 0 0.00 0 68729 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-08-2021 01:39:40 to 23-08-2021 01:39:44.

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
23:07:48 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:49 26901 8192000000 10384643696 10707132416 1434950752 14.905 100 92 184 0 1471047744 6304544 29792448
23:10:23 15699 8192000000 10462626528 10797699072 1507995904 15.546 100 89 178 0 1545280688 8623024 28661760
23:11:44 19329 8192000000 10512575232 10861977600 1565607104 16.044 100 81 163 2 1599791760 9528688 24655968
23:13:13 12043 8192000000 10593654352 10954047488 1647176752 16.741 100 80 160 0 1682640208 11585632 23877824
23:14:28 8890 8192000000 10639140240 11006480384 1693963136 17.135 100 79 158 0 1729437280 13206544 22267600
23:15:43 4809 8192000000 10685074752 11060248576 1748954016 17.593 100 82 164 1 1785027408 13162192 22911200
23:16:49 4038 8192000000 10736084832 11115188224 1792406352 17.952 100 89 178 0 1836324144 19116320 24801472
23:17:55 2183 8192000000 10798769440 11183370240 1854990464 18.463 100 96 192 1 1894147856 11984336 27173056
23:18:58 3440 8192000000 10806165200 11193475072 1862308960 18.522 100 76 152 3 1894164256 11200448 20654848
23:20:03 32576 8192000000 10795864896 11210272768 1876002160 18.633 100 44 88 1 1895727840 7233584 12492096
23:21:36 7881 8192000000 10791754752 11224608768 1886281152 18.716 100 24 48 1 1899874864 6951616 6642096
23:22:44 12055 8192000000 10810264480 11250978816 1896718928 18.800 100 14 28 2 1914399552 14405856 3274768
23:23:57 39429 8192000000 10811839936 11248263168 1900390272 18.829 100 24 46 2 1912654192 5750896 6513024
23:25:37 4524 8192000000 10830649360 11263471616 1911781120 18.921 100 37 74 1 1928678016 6647088 10249808
23:26:44 5672 8192000000 10834747216 11267686400 1916513504 18.959 100 30 59 3 1931323408 6452928 8356976
23:27:51 40479 8192000000 10759727552 11182194688 1763869136 17.716 93 30 60 1 1777070096 4871248 8329712
23:29:50 5410 8192000000 10740833632 11155107840 1793237008 17.958 97 37 74 1 1812210928 7970336 11003584
23:30:57 9952 8192000000 10705477920 11119849472 1739623776 17.516 97 23 46 1 1751567184 5284080 6659328
23:32:09 6543 8192000000 10724865584 11141115904 1798343456 18.000 99 27 54 1 1812549280 6063408 8142416
23:33:18 44428 8192000000 10745803216 11165761536 1812377120 18.115 99 29 58 2 1826189504 4941088 8871296
23:35:07 6563 8192000000 10771712128 11196170240 1857427344 18.482 100 32 64 0 1873483808 7007968 9048496
23:36:16 8453 8192000000 10794668896 11216515072 1868236544 18.570 100 22 44 0 1879420528 4821984 6362000
23:37:30 81008 8192000000 10835272368 11249680384 1894641520 18.783 99 33 64 1 1909090512 4988224 9460768
23:39:53 7770 8192000000 10883566480 11308367872 1951552320 19.239 100 67 134 3 1984223424 13261232 19409872
23:41:06 10863 8192000000 10917400768 11338629120 1973420304 19.413 100 70 139 3 2007091648 13884784 19786560
23:42:19 10408 8192000000 10908412144 11332448256 1959994528 19.306 99 62 124 1 1992822176 15295360 17532288
23:43:33 16363 8192000000 10926056144 11357220864 1988479264 19.532 100 57 114 1 2026084208 21356032 16248912
23:45:14 10209 8192000000 10945525680 11381596160 2006802208 19.676 100 59 118 1 2047311872 22612656 17897008
23:46:32 9654 8192000000 10947003328 11382956032 2001966080 19.638 100 60 120 0 2045751472 25221648 18563744
23:47:46 6946 8192000000 10964877824 11394170880 2003732256 19.652 100 59 118 1 2049985088 28675552 17577280
23:48:58 12297 8192000000 10951097120 11389714432 1991641840 19.557 100 46 90 3 2038900224 33491424 13766960
23:50:21 12557 8192000000 10956139424 11388833792 1997528864 19.603 100 51 97 1 2043225232 30959072 14737296
23:51:41 9337 8192000000 10982248560 11402641408 2006855168 19.677 100 76 152 2 2067612288 37111968 23645152
23:52:58 8025 8192000000 10988578512 11414507520 2007291440 19.680 100 64 128 1 2073822032 45833648 20696944
23:54:13 4757 8192000000 11000762192 11412758528 2001411344 19.634 100 81 160 0 2075638048 49817120 24409584
23:55:22 2253 8192000000 11009661760 11425415168 2002706768 19.644 100 86 173 1 2091677488 61436400 27534320
23:56:30 3318 8192000000 10978323504 11409633280 1986414608 19.515 99 69 138 2 2067914496 57385664 24114224
23:57:39 8884 8192000000 11003010800 11437408256 2009128496 19.695 100 60 117 1 2082260288 54093856 19037936
23:58:53 8354 8192000000 10989007968 11420971008 2000557952 19.627 100 56 111 1 2070473344 51736192 18179200
00:00:16 14422 8192000000 10953243952 11405963264 1961287392 19.316 99 38 76 0 2034596336 60046240 13262704
00:01:47 14780 8192000000 10952382688 11397619712 1967769696 19.368 100 30 60 0 2031237856 52987984 10480176
00:03:03 27458 8192000000 10944171936 11406643200 1966704432 19.359 100 32 64 1 2041006496 63470960 10831104
00:04:37 32745 8192000000 10936478416 11392266240 1954533216 19.263 100 30 60 3 2041240912 75183296 11524400
00:07:01 59201 8192000000 10872593520 11349524480 1876277456 18.635 97 7 14 1 1910442144 32306096 1858592
00:09:02 108992 8192000000 10871215120 11321110528 1831276736 18.270 94 8 16 0 1851388944 18383872 1728336
00:12:04 28258 8192000000 10899809568 11353006080 1958932736 19.298 100 17 34 3 1978191184 14416384 4842064
00:13:42 15080 8192000000 10923113872 11343757312 1960206448 19.308 100 61 120 1 1987750960 9579680 17964832
00:14:58 76801 8192000000 10890831424 11343872000 1904788560 18.865 97 34 66 3 1927047264 12896144 9362560
00:17:23 24523 8192000000 10910302048 11364380672 1971453632 19.397 100 34 68 3 1990995648 10523744 9018272
00:18:51 50880 8192000000 10918438256 11380785152 1981100896 19.473 100 22 44 1 1998479920 10911472 6467552
00:20:43 31102 8192000000 10938421168 11402420224 1998316928 19.609 100 45 90 0 2025074752 13829184 12928640
00:22:16 64211 8192000000 10931444080 11405443072 2006356192 19.673 100 19 38 2 2023161152 12083024 4721936
00:24:21 35817 8192000000 10926101872 11396378624 1987093840 19.521 99 17 34 0 2003783952 11959568 4730544
00:25:57 25337 8192000000 10906122944 11394224128 1989744800 19.542 100 10 20 1 2000626656 8750272 2131584
00:27:23 52395 8192000000 10923414304 11405590528 1997427808 19.602 100 8 16 0 2006523728 6599120 2496800
00:29:15 34182 8192000000 10918140640 11397554176 1958484672 19.294 98 26 52 2 1975357648 9837744 7035232
00:31:13 92436 8192000000 10897707920 11397242880 1968822656 19.376 99 15 30 0 1988292064 15003136 4466272
00:33:47 47374 8192000000 10895380704 11383222272 1957268432 19.284 99 16 32 1 1982506192 20440448 4797312
00:35:48 26089 8192000000 10910670560 11362607104 1948299056 19.213 99 44 87 1 1969523200 9282016 11942128
00:37:15 48036 8192000000 10876952448 11348680704 1889497168 18.742 96 15 30 0 1901045424 7896864 3651392
00:39:03 68120 8192000000 10879426224 11333488640 1841995088 18.357 94 16 32 2 1856580816 10466928 4118800
00:41:12 30230 8192000000 10903191104 11371438080 1969486976 19.381 100 13 26 0 1982698560 9381248 3830336
00:42:49 75407 8192000000 10884242816 11338829824 1849002848 18.414 94 23 46 0 1873687712 18795136 5889728
00:45:07 23496 8192000000 10865604512 11325489152 1885427488 18.709 97 31 62 2 1917179920 23331488 8420944
00:46:37 121254 8192000000 10842336944 11295293440 1835469536 18.304 95 17 34 1 1859394144 19107072 4817536
00:50:10 35778 8192000000 10865052288 11339948032 1913567728 18.935 99 18 36 0 1935729216 16980224 5181264
00:52:06 52158 8192000000 10849473248 11333201920 1936929168 19.122 100 8 16 0 1949065936 10261808 1874960
00:54:15 40540 8192000000 10846723664 11326255104 1920104464 18.988 99 7 14 0 1929184464 7270512 1809488
00:56:08 68516 8192000000 10869833984 11339325440 1926748928 19.041 99 17 33 1 1939363568 7701552 4913088
00:58:21 40940 8192000000 10868926464 11348746240 1912550800 18.927 98 10 20 1 1923419584 8130656 2738128
01:00:07 33288 8192000000 10844884896 11312660480 1858844496 18.494 96 14 28 3 1871325616 8167712 4313408
01:01:56 73701 8192000000 10867592704 11350122496 1936887360 19.122 100 12 22 3 1955367984 15039776 3440848
01:04:54 60324 8192000000 10858519088 11332222976 1895324672 18.789 98 16 32 0 1911837904 12143120 4370112
01:06:55 87613 8192000000 10882770368 11365400576 1945133408 19.188 99 23 46 0 1966328512 14866736 6328368
01:09:26 33201 8192000000 10921306768 11379605504 1964670320 19.343 99 33 66 1 1986357824 12904896 8782608
01:11:12 85734 8192000000 10912154400 11398086656 1980600944 19.469 100 11 22 0 2002163040 18889040 2673056
01:13:53 64366 8192000000 10899245808 11394007040 1984643968 19.501 100 11 22 0 2001693664 13865984 3183712
01:16:04 56821 8192000000 10896422112 11391422464 1965394400 19.349 99 11 22 2 1982458800 14494832 2569568
01:18:01 45054 8192000000 10896704928 11381780480 1981444912 19.476 100 16 30 1 1996488032 10942480 4100640
01:19:55 34065 8192000000 10902089488 11373187072 1947457392 19.206 98 41 80 1 1968277776 8741360 12079024
01:21:32 108570 8192000000 10880939280 11375349760 1931944368 19.082 98 13 26 0 1951752432 15720048 4088016
01:24:30 65806 8192000000 10869243776 11335225344 1886573216 18.718 96 27 54 3 1904987216 10395312 8018688
01:26:36 77540 8192000000 10865910192 11340902400 1865750640 18.550 95 12 24 0 1878434464 9859744 2824080
01:29:01 60561 8192000000 10870573072 11354038272 1888391760 18.733 97 15 30 1 1909528464 16635792 4500912
01:31:06 110297 8192000000 10877585952 11342716928 1903071472 18.851 98 12 24 0 1922066016 14720688 4273856
01:34:04 41062 8192000000 10855766080 11345125376 1932240304 19.085 100 13 26 0 1949515712 13789744 3485664
01:35:45 62273 8192000000 10853804080 11327864832 1898700944 18.816 98 15 30 1 1913917328 11329216 3887168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-08-2021 01:39:46 to 23-08-2021 01:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 23-08-2021 01:40:02 to 23-08-2021 01:40:03.

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          242571
MON$OLDEST_ACTIVE               243983
MON$OLDEST_SNAPSHOT             243983
MON$NEXT_TRANSACTION            243983
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                       2050080
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:305: 
:306: mscs-test-161.budzdorov.ru    Mon Aug 23 01:37:46 2021
:307:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:308: 
:309: 
:310: mscs-test-161.budzdorov.ru    Mon Aug 23 01:37:50 2021
:311:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:312: 
:313: 
:314: mscs-test-161.budzdorov.ru    Mon Aug 23 01:37:56 2021
:315:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:316: 
:317: 
:318: mscs-test-161.budzdorov.ru    Mon Aug 23 01:38:03 2021
:319:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:320: 
:321: 
:322: mscs-test-161.budzdorov.ru    Mon Aug 23 01:38:03 2021
:323:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:324: 
--- end of diff output ---

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

23.08.21 01:40:04. 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-08-23 01:40:07 No severe PSQL-related problems occured
23.08.21 01:40:08. End of report.