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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16494528 kB
MemAvailable 31393252 kB
Buffers 0 kB
Cached 15252320 kB
SwapCached 2440 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.10
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 2022-05-30 22:38:16
DTS_END                         2022-05-30 22:38:16.6790
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/51312
TRN_ID                          237033
ATT_ID                          666
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: 9404.63 21651 1128556
customer order: creation 167.82 340 20138
customer order: refuse 14.43 365 1731
order to supplier: creation 44.28 1193 5313
order to supplier: removal 3.38 105735 406
invoice (draft): creation 44.23 3112 5307
invoice (draft): removal 55.08 715 6609
invoice accept: apply 41.24 17167 4949
invoice accept: cancel 50.59 741 6071
customer reserve: creation 3798.05 170 455766
customer reserve: removal 4878.00 58 585360
realization accept: apply 82.83 226 9939
realization accept: cancel 97.35 110 11682
payment from customer: creation 58.04 124 6965
payment from customer: removal 5.29 71 635
payment to supplier: creation 48.76 160 5851
payment to supplier: removal 3.39 58 407
service: total inventory turnovers 4.60 1013 552
service: total monetary turnovers 6.40 28 768
service: aggregate perf. data 0.86 20459 103
service: refresh index statistics 0.03 281174 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-05-2022 22:39:51 to 30-05-2022 22:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3375
TEST_TIME 2 4242
TEST_TIME 3 3706
TEST_TIME 4 3316
TEST_TIME 5 3959
TEST_TIME 6 4035
TEST_TIME 7 4250
TEST_TIME 8 4694
TEST_TIME 9 4808
TEST_TIME 10 5332
TEST_TIME 11 5301
TEST_TIME 12 5762
TEST_TIME 13 5909
TEST_TIME 14 6154
TEST_TIME 15 6673
TEST_TIME 16 6836
TEST_TIME 17 7085
TEST_TIME 18 7746
TEST_TIME 19 8021
TEST_TIME 20 8357
TEST_TIME 21 8833
TEST_TIME 22 8929
TEST_TIME 23 8942
TEST_TIME 24 9129
TEST_TIME 25 9401
TEST_TIME 26 9583
TEST_TIME 27 10262
TEST_TIME 28 10659
TEST_TIME 29 10536
TEST_TIME 30 10406
TEST_TIME 31 10312
TEST_TIME 32 10249
TEST_TIME 33 10327
TEST_TIME 34 10116
TEST_TIME 35 10177
TEST_TIME 36 10075
TEST_TIME 37 10117
TEST_TIME 38 10085
TEST_TIME 39 9944
TEST_TIME 40 9860
TEST_TIME 41 9881
TEST_TIME 42 9859
TEST_TIME 43 9764
TEST_TIME 44 9721
TEST_TIME 45 9709
TEST_TIME 46 9592
TEST_TIME 47 9583
TEST_TIME 48 9531
TEST_TIME 49 9404
TEST_TIME 50 9471
TEST_TIME 51 9466
TEST_TIME 52 9533
TEST_TIME 53 9500
TEST_TIME 54 9488
TEST_TIME 55 9738
TEST_TIME 56 9741
TEST_TIME 57 9725
TEST_TIME 58 9728
TEST_TIME 59 9766
TEST_TIME 60 9803
TEST_TIME 61 9821
TEST_TIME 62 9752
TEST_TIME 63 9988
TEST_TIME 64 10020
TEST_TIME 65 9871
TEST_TIME 66 9836
TEST_TIME 67 9749
TEST_TIME 68 9796
TEST_TIME 69 9771
TEST_TIME 70 9703
TEST_TIME 71 9653
TEST_TIME 72 9662
TEST_TIME 73 9591
TEST_TIME 74 9561
TEST_TIME 75 9540
TEST_TIME 76 9472
TEST_TIME 77 9444
TEST_TIME 78 9464
TEST_TIME 79 9441
TEST_TIME 80 9448
TEST_TIME 81 9488
TEST_TIME 82 9445
TEST_TIME 83 9579
TEST_TIME 84 9599
TEST_TIME 85 9611
TEST_TIME 86 9629
TEST_TIME 87 9602
TEST_TIME 88 9653
TEST_TIME 89 9652
TEST_TIME 90 9679
TEST_TIME 91 9583
TEST_TIME 92 9522
TEST_TIME 93 9511
TEST_TIME 94 9490
TEST_TIME 95 9440
TEST_TIME 96 9458
TEST_TIME 97 9448
TEST_TIME 98 9426
TEST_TIME 99 9467
TEST_TIME 100 9488
TEST_TIME 101 9446
TEST_TIME 102 9490
TEST_TIME 103 9529
TEST_TIME 104 9506
TEST_TIME 105 9532
TEST_TIME 106 9558
TEST_TIME 107 9585
TEST_TIME 108 9556
TEST_TIME 109 9580
TEST_TIME 110 9543
TEST_TIME 111 9470
TEST_TIME 112 9507
TEST_TIME 113 9463
TEST_TIME 114 9458
TEST_TIME 115 9422
TEST_TIME 116 9402
TEST_TIME 117 9398
TEST_TIME 118 9328
TEST_TIME 119 9356
TEST_TIME 120 9407

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-05-2022 22:39:54 to 30-05-2022 22:39:58.

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 1549916 1549916 0 0.00 0 26653 6 0 0
doc_list_biud 1549916 1549916 0 0.00 0 27099 28 0 0
sp_add_invoice_to_stock 5115 4949 166 3.24 39 168814 17167 0 166
sp_cancel_adding_invoice 6071 6071 0 0.00 3 61284 741 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 12397 366 0 0
sp_cancel_customer_reserve 585360 585360 0 0.00 1 27100 58 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 3 6374 71 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 2 3681 59 0 0
sp_cancel_supplier_invoice 6625 6609 16 0.24 1 19265 715 0 16
sp_cancel_supplier_order 406 406 0 0.00 5 938427 105735 0 0
sp_cancel_write_off 11682 11682 0 0.00 1 25925 111 0 0
sp_client_order 20138 20138 0 0.00 3 19849 341 0 0
sp_customer_reserve 455766 455766 0 0.00 0 55954 171 0 0
sp_fill_shopping_cart 30745 30745 0 0.00 1 14739 37 0 0
sp_fill_shopping_cart_clo_res 455761 455761 0 0.00 0 14444 14 0 0
sp_get_clo_for_invoice 4944 4944 0 0.00 0 35537 287 0 0
sp_kill_cost_storno 18795 18795 0 0.00 0 25888 58 0 0
sp_kill_qstorno_ret_qs2qd 1189417 1189417 0 0.00 0 27096 31 0 0
sp_kill_qty_storno 626742 626742 0 0.00 0 27099 68 0 0
sp_lock_selected_doc 20573 20573 0 0.00 0 7394 4 0 0
sp_make_cost_storno 27699 27699 0 0.00 0 24410 70 0 0
sp_multiply_rows_for_pdistr 27699 27699 0 0.00 0 25791 32 0 0
sp_multiply_rows_for_qdistr 20138 20138 0 0.00 0 11504 159 0 0
sp_pay_from_customer 6965 6965 0 0.00 4 21819 125 0 0
sp_pay_to_supplier 5851 5851 0 0.00 5 23956 161 0 0
sp_payment_common 12816 12816 0 0.00 1 21865 45 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 12389 341 0 0
sp_qd_handle_on_invoice_upd_sts 11015 11015 0 0.00 0 19832 322 0 0
sp_qd_handle_on_reserve_upd_sts 9939 9939 0 0.00 0 18076 71 0 0
sp_reserve_write_off 9939 9939 0 0.00 6 26194 226 0 0
sp_supplier_invoice 5307 5307 0 0.00 7 89843 3113 0 0
sp_supplier_order 5313 5313 0 0.00 6 31968 1193 0 0
srv_aggregate_perf_data 103 103 0 0.00 2812 258057 20460 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2840 113468 43598 0 0
srv_find_qd_qs_mism 1081409 1081409 0 0.00 0 26649 5 0 0
srv_make_invnt_saldo 552 552 0 0.00 31 52541 1013 0 0
srv_make_money_saldo 768 768 0 0.00 1 3308 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 194620 445446 281175 0 0
t$perf-abend:sp_add_invoice_to_stock 166 166 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4949 4949 0 0.00 0 11892 152 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 0 7795 111 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 2115 10 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 2327 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 1690 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 1876 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 395 13 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 21932 1670 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 4948 17 0 0
t$perf-norm:sp_client_order 20138 20138 0 0.00 0 19947 21 0 0
t$perf-norm:sp_customer_reserve 944 944 0 0.00 0 2718 10 0 0
t$perf-norm:sp_pay_from_customer 6965 6965 0 0.00 0 7591 10 0 0
t$perf-norm:sp_pay_to_supplier 5851 5851 0 0.00 0 6261 12 0 0
t$perf-norm:sp_reserve_write_off 9939 9939 0 0.00 0 19882 20 0 0
t$perf-norm:sp_supplier_invoice 5307 5307 0 0.00 0 10748 22 0 0
t$perf-norm:sp_supplier_order 5313 5313 0 0.00 0 4171 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 229 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1006 17 0 0
t$perf-norm:srv_make_invnt_saldo 552 552 0 0.00 0 556 17 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 4112 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10220 9888 332 3.24 0 3745 18 0 332
v_all_customers 37032 37032 0 0.00 0 63 0 0 0
v_all_suppliers 29736 29736 0 0.00 0 64 1 0 0
v_all_wares 40276 40276 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 2361 8 0 0
v_cancel_client_order 3462 3462 0 0.00 0 4224 6 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 788 4 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1532 5 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 327 3 0 0
v_cancel_supplier_invoice 798 766 32 4.01 0 2135 19 0 32
v_cancel_supplier_order 812 812 0 0.00 0 1654 13 0 0
v_cancel_write_off 2020 2020 0 0.00 0 5300 16 0 0
v_max_id_clo_ord 5313 5313 0 0.00 0 71 1 0 0
v_max_id_clo_res 944 944 0 0.00 0 6281 36 0 0
v_max_id_ord_sup 5294 5294 0 0.00 0 3268 3 0 0
v_max_non_paid_invoice 1590 1590 0 0.00 0 3240 7 0 0
v_max_non_paid_realizn 3514 3514 0 0.00 0 2318 4 0 0
v_min_id_clo_ord 5313 5313 0 0.00 0 3130 7 0 0
v_min_id_clo_res 944 944 0 0.00 0 1644 7 0 0
v_min_id_ord_sup 5294 5294 0 0.00 0 4548 5 0 0
v_min_non_paid_invoice 5851 5851 0 0.00 0 21853 44 0 0
v_min_non_paid_realizn 6965 6965 0 0.00 0 21693 32 0 0
v_our_firm 10146 10146 0 0.00 0 26 1 0 0
v_reserve_write_off 19878 19878 0 0.00 0 5014 6 0 0
x_lock_depdocs_on_canc_invoice 12297 12297 0 0.00 0 35863 114 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 8820 339 0 0
x_make_qstorno_1000_1200 5313 5313 0 0.00 1 31925 1119 0 0
x_make_qstorno_1200_2000 5307 5307 0 0.00 3 75101 2974 0 0
x_make_qstorno_2100_3300 455766 455766 0 0.00 0 55945 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-05-2022 22:39:59 to 30-05-2022 22:40:03.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:20 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:21 36735 8192000000 9923459056 10236850176 1005537872 10.932 100 88 176 0 1033793312 6219856 22035584
20:11:03 26679 8192000000 9961045200 10286018560 1038329120 11.249 100 90 180 0 1070559824 9213024 23017680
20:12:32 19948 8192000000 9996848432 10334314496 1080225728 11.650 100 87 174 1 1112863600 10416048 22221824
20:13:54 12996 8192000000 10034079488 10384048128 1126922160 12.092 100 73 146 0 1155769408 11738112 17109136
20:15:08 5574 8192000000 10079440880 10442383360 1171878096 12.514 100 73 146 0 1201159792 12409376 16872320
20:16:16 4250 8192000000 10132300592 10501623808 1214338384 12.909 100 81 162 1 1246562384 14166736 18057264
20:17:24 4387 8192000000 10153395536 10529521664 1245319888 13.195 100 71 141 2 1272287504 11773184 15194432
20:18:32 3146 8192000000 10189102064 10566721536 1276811248 13.484 100 92 183 0 1310478832 13284672 20382912
20:19:36 2023 8192000000 10217963888 10602086400 1302748448 13.720 100 91 182 0 1336179024 12537024 20893552
20:20:41 2917 8192000000 10216078944 10606178304 1307000400 13.759 100 72 142 1 1332453104 9530528 15922176
20:21:47 3537 8192000000 10212413344 10613649408 1316149328 13.842 100 52 102 2 1333855936 5352032 12354576
20:22:52 14861 8192000000 10204926864 10622251008 1320752864 13.884 100 16 32 1 1330098320 5312144 4033312
20:24:07 9164 8192000000 10219031408 10634575872 1330319392 13.970 100 29 56 2 1343336224 6669584 6347248
20:25:17 52961 8192000000 10240940240 10657902592 1341514256 14.071 100 42 85 2 1357507808 6325232 9668320
20:27:13 8772 8192000000 10239809920 10657021952 1337348896 14.034 100 26 52 0 1357275632 14578240 5348496
20:28:24 4181 8192000000 10221057600 10632130560 1322164736 13.896 100 31 64 2 1335331792 5613120 7553936
20:29:29 11393 8192000000 10195991232 10611535872 1296058896 13.659 99 29 58 2 1308990160 5435184 7496080
20:30:42 11278 8192000000 10168578064 10579628032 1231552256 13.068 95 37 74 0 1245838608 5565200 8721152
20:31:54 5733 8192000000 10158629072 10562625536 1256567040 13.299 99 32 64 0 1271116528 7028896 7520592
20:33:03 4377 8192000000 10147497584 10548588544 1236714096 13.116 98 30 60 1 1249192048 4767104 7710848
20:34:10 7635 8192000000 10178662144 10585022464 1276730256 13.483 100 23 46 2 1292044704 10068048 5246400
20:35:28 24082 8192000000 10184323712 10598928384 1293784352 13.639 100 31 62 1 1307808368 5426512 8597504
20:36:55 5979 8192000000 10213410560 10618429440 1307745488 13.766 100 48 96 0 1324631424 6018480 10867456
20:38:04 14086 8192000000 10215226336 10632482816 1322213616 13.897 100 26 52 0 1332943360 4147456 6582288
20:39:24 21887 8192000000 10250673216 10665480192 1346120208 14.113 100 42 80 3 1364653568 9707008 8826352
20:40:49 24576 8192000000 10267372832 10684653568 1359741104 14.235 100 38 76 0 1379889264 11632144 8516016
20:42:18 51532 8192000000 10279071680 10703650816 1371955488 14.345 100 42 84 0 1396942560 15414368 9572704
20:44:28 20303 8192000000 10310038224 10735616000 1396164288 14.561 100 40 76 3 1426808080 20663904 9979888
20:46:21 4937 8192000000 10337326880 10759110656 1410326800 14.687 100 47 92 1 1444516800 23043264 11146736
20:47:41 2839 8192000000 10354096496 10768412672 1416370832 14.741 100 70 140 0 1458476464 25155248 16950384
20:48:47 3531 8192000000 10348363392 10762395648 1411631232 14.698 100 76 149 2 1456065008 26148544 18285232
20:49:55 5191 8192000000 10354300848 10770411520 1401955872 14.612 99 66 131 0 1448256816 30700288 15600656
20:51:05 6274 8192000000 10353173104 10770411520 1400297104 14.598 99 65 130 1 1452049408 34611152 17141152
20:52:22 6880 8192000000 10362058432 10782756864 1416391504 14.741 100 61 122 0 1470579072 37939936 16247632
20:53:33 7415 8192000000 10363082832 10780213248 1415408784 14.732 100 69 138 0 1471640368 39359872 16871712
20:54:44 5556 8192000000 10354871616 10773274624 1385679360 14.467 99 65 130 1 1454438560 51834944 16924256
20:55:56 5899 8192000000 10352710208 10757087232 1386146960 14.471 100 88 173 1 1454193600 46752128 21294512
20:57:16 13410 8192000000 10330048176 10757373952 1386872016 14.478 100 44 88 1 1442453152 43118560 12462576
20:58:45 7388 8192000000 10335405168 10753826816 1377308976 14.392 100 55 110 2 1435137808 42884800 14944032
20:59:59 16200 8192000000 10286961600 10712403968 1318465568 13.863 96 27 54 1 1363889296 38068464 7355264
21:01:17 21098 8192000000 10282659408 10702139392 1330001984 13.967 97 29 58 1 1376801552 39186912 7612656
21:02:40 31007 8192000000 10277197024 10704523264 1306699824 13.756 97 23 46 0 1355056768 41779872 6577072
21:04:13 23068 8192000000 10271517504 10711044096 1337328304 14.033 100 15 30 1 1379383952 37237648 4818000
21:05:38 63934 8192000000 10256086416 10709495808 1319630176 13.873 97 16 32 0 1357291856 33883360 3778320
21:07:43 34629 8192000000 10253390656 10686238720 1318121920 13.860 98 56 112 2 1343231760 11466384 13643456
21:09:36 20240 8192000000 10282587424 10707488768 1336984144 14.030 98 50 96 3 1353654352 6115456 10554752
21:10:59 47075 8192000000 10276793936 10726744064 1367072352 14.301 100 15 30 2 1380634928 9845024 3717552
21:13:11 60245 8192000000 10303066864 10746335232 1371671312 14.342 99 21 42 1 1389335408 12176928 5487168
21:15:15 30677 8192000000 10268562896 10718547968 1364882480 14.281 100 12 24 0 1379179664 11309872 2987312
21:16:50 65831 8192000000 10277805424 10725482496 1360052016 14.238 99 16 32 0 1374610048 11098992 3459040
21:18:57 32361 8192000000 10274074832 10730323968 1371821824 14.343 100 8 15 1 1389499760 15974272 1703664
21:20:32 66515 8192000000 10275454960 10747293696 1376398880 14.384 100 11 22 0 1391630976 12471504 2760592
21:22:54 77302 8192000000 10250993936 10706923520 1324967968 13.922 96 21 42 0 1341874896 12301072 4605856
21:25:19 44790 8192000000 10280854288 10735271936 1378418704 14.402 100 10 20 1 1391786912 10776288 2591920
21:27:06 61506 8192000000 10286719776 10737639424 1359645568 14.234 98 15 29 0 1375541104 12412432 3483104
21:29:31 72190 8192000000 10282150304 10751250432 1378809680 14.406 100 14 27 3 1400326848 17907920 3609248
21:31:44 66183 8192000000 10312049728 10767409152 1388414464 14.492 99 32 64 1 1415109472 19262224 7432784
21:33:57 86235 8192000000 10288253680 10751705088 1361382592 14.250 98 21 43 0 1382626768 16575232 4668944
21:36:26 47039 8192000000 10290903808 10743123968 1376684976 14.387 100 29 58 0 1401230704 18338416 6207312
21:38:18 77585 8192000000 10290801120 10744012800 1351845968 14.164 98 21 42 1 1369930832 13661392 4423472
21:40:36 57196 8192000000 10299919360 10769457152 1377397488 14.393 99 9 18 0 1404248768 24706240 2145040
21:44:20 61607 8192000000 10287632352 10744815616 1377748608 14.396 99 26 54 0 1395505168 12235216 5521344
21:46:23 63977 8192000000 10274258176 10736029696 1345187936 14.104 97 16 32 1 1364733440 15933072 3612432
21:48:31 47199 8192000000 10268646912 10711482368 1309830736 13.785 95 13 25 1 1323796880 11476224 2489920
21:50:19 30326 8192000000 10263042976 10723573760 1344627472 14.099 98 11 22 0 1358296944 10844096 2825376
21:51:49 24412 8192000000 10263821200 10715787264 1358124272 14.221 99 24 47 2 1371282016 7183776 5973968
21:53:21 81442 8192000000 10252186784 10713767936 1331562976 13.981 97 6 12 0 1342684672 9821728 1299968
21:55:43 54827 8192000000 10260145184 10725449728 1309057696 13.778 95 14 28 0 1328456656 16624704 2774256
21:57:39 44461 8192000000 10271282528 10712731648 1347090160 14.121 98 36 72 1 1370780048 15488240 8201648
21:59:27 91948 8192000000 10263146112 10705010688 1306760832 13.757 96 17 34 1 1332307280 21269632 4276816
22:01:59 78083 8192000000 10259988656 10712326144 1353415904 14.178 100 10 20 1 1365298976 9844144 2038928
22:04:18 15956 8192000000 10277592160 10706542592 1357288560 14.213 100 57 114 0 1381849632 11967984 12593088
22:05:34 7280 8192000000 10281171600 10710458368 1356813360 14.209 100 52 104 1 1379206816 10964096 11429360
22:06:45 78602 8192000000 10287284000 10732609536 1347541776 14.125 98 25 50 0 1370007696 16970112 5495808
22:09:07 82656 8192000000 10273933472 10721411072 1339231760 14.050 97 21 42 1 1349854512 6436752 4186000
22:13:52 59920 8192000000 10259495984 10721497088 1336222112 14.023 97 13 26 0 1347736416 8876096 2638208
22:15:53 29508 8192000000 10294819536 10742820864 1374003904 14.363 99 24 45 0 1390033744 10280848 5748992
22:17:26 92597 8192000000 10291343328 10754043904 1368369904 14.312 99 9 18 0 1385542384 15314000 1858480
22:19:59 14498 8192000000 10302924656 10743697408 1372460256 14.349 99 58 118 1 1398673184 13251920 12961008
22:21:17 44428 8192000000 10294096656 10746200064 1345195520 14.104 97 19 39 0 1365046480 14819744 5031216
22:23:06 113468 8192000000 10301434112 10761527296 1378631488 14.404 99 18 35 2 1397947760 15652656 3663616
22:26:00 69780 8192000000 10289750192 10729549824 1309035184 13.777 94 11 22 0 1320130640 8687040 2408416
22:28:16 70398 8192000000 10285580704 10746982400 1381801232 14.433 100 12 24 0 1398675744 14391744 2482768
22:30:30 63771 8192000000 10304207648 10760388608 1379048208 14.408 99 26 51 0 1397048096 12132528 5867360
22:32:35 92082 8192000000 10279491984 10746044416 1346224288 14.113 97 10 20 0 1367057408 18327696 2505424
22:35:20 59703 8192000000 10304055440 10766725120 1395439120 14.554 100 15 29 0 1413200720 13486928 4274672
22:38:13 12670 8192000000 9024802848 9242406912 190575072 2.273 14 2 4 0 197724752 6866016 283664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-05-2022 22:40:05 to 30-05-2022 22:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 166 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 30-05-2022 22:40:21 to 30-05-2022 22:40:22.

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          237032
MON$OLDEST_ACTIVE               237410
MON$OLDEST_SNAPSHOT             237410
MON$NEXT_TRANSACTION            237410
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                       2018304
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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 ---
:216: 
:217: mscs-test-161.budzdorov.ru    Mon May 30 22:38:29 2022
:218:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:219: 
:220: 
:221: mscs-test-161.budzdorov.ru    Mon May 30 22:38:29 2022
:222:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:223: 
--- end of diff output ---

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

30.05.22 22:40:23. 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
2022-05-30 22:40:26 No severe PSQL-related problems occured
30.05.22 22:40:27. End of report.