Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.05.2022 22:38
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 16160924 kB
MemAvailable 31352844 kB
Buffers 0 kB
Cached 15511676 kB
SwapCached 2552 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-26 22:38:16
DTS_END                         2022-05-26 22:38:16.8390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46640
TRN_ID                          239922
ATT_ID                          714
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: 9840.60 26299 1180872
customer order: creation 171.99 337 20639
customer order: refuse 14.48 359 1738
order to supplier: creation 46.71 1333 5605
order to supplier: removal 3.38 112590 405
invoice (draft): creation 46.08 3310 5529
invoice (draft): removal 57.20 749 6864
invoice accept: apply 42.95 17029 5154
invoice accept: cancel 51.86 797 6223
customer reserve: creation 4040.56 163 484867
customer reserve: removal 5052.27 59 606273
realization accept: apply 83.80 176 10056
realization accept: cancel 96.89 105 11627
payment from customer: creation 60.52 115 7262
payment from customer: removal 5.94 109 713
payment to supplier: creation 49.98 138 5998
payment to supplier: removal 3.58 77 430
service: total inventory turnovers 4.85 1044 582
service: total monetary turnovers 6.78 28 814
service: aggregate perf. data 0.74 24336 89
service: refresh index statistics 0.03 363116 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-05-2022 22:39:26 to 26-05-2022 22:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4199
TEST_TIME 2 3725
TEST_TIME 3 3248
TEST_TIME 4 3608
TEST_TIME 5 4465
TEST_TIME 6 4572
TEST_TIME 7 4581
TEST_TIME 8 4894
TEST_TIME 9 4957
TEST_TIME 10 5734
TEST_TIME 11 6131
TEST_TIME 12 6683
TEST_TIME 13 7182
TEST_TIME 14 7281
TEST_TIME 15 7301
TEST_TIME 16 7288
TEST_TIME 17 7436
TEST_TIME 18 7650
TEST_TIME 19 7792
TEST_TIME 20 8135
TEST_TIME 21 8264
TEST_TIME 22 8455
TEST_TIME 23 8610
TEST_TIME 24 8939
TEST_TIME 25 9547
TEST_TIME 26 9526
TEST_TIME 27 9850
TEST_TIME 28 10001
TEST_TIME 29 10216
TEST_TIME 30 10184
TEST_TIME 31 10583
TEST_TIME 32 10919
TEST_TIME 33 10751
TEST_TIME 34 10722
TEST_TIME 35 10746
TEST_TIME 36 10749
TEST_TIME 37 10677
TEST_TIME 38 10497
TEST_TIME 39 10641
TEST_TIME 40 10818
TEST_TIME 41 10790
TEST_TIME 42 10757
TEST_TIME 43 10718
TEST_TIME 44 10606
TEST_TIME 45 10495
TEST_TIME 46 10388
TEST_TIME 47 10307
TEST_TIME 48 10267
TEST_TIME 49 10325
TEST_TIME 50 10256
TEST_TIME 51 10210
TEST_TIME 52 10176
TEST_TIME 53 10170
TEST_TIME 54 10154
TEST_TIME 55 10229
TEST_TIME 56 10182
TEST_TIME 57 10102
TEST_TIME 58 10112
TEST_TIME 59 10064
TEST_TIME 60 9960
TEST_TIME 61 9876
TEST_TIME 62 9827
TEST_TIME 63 9766
TEST_TIME 64 9802
TEST_TIME 65 9846
TEST_TIME 66 9843
TEST_TIME 67 9924
TEST_TIME 68 9935
TEST_TIME 69 9909
TEST_TIME 70 9964
TEST_TIME 71 9953
TEST_TIME 72 9956
TEST_TIME 73 9898
TEST_TIME 74 10004
TEST_TIME 75 10095
TEST_TIME 76 10165
TEST_TIME 77 10137
TEST_TIME 78 10102
TEST_TIME 79 10019
TEST_TIME 80 10029
TEST_TIME 81 9952
TEST_TIME 82 9944
TEST_TIME 83 9922
TEST_TIME 84 9869
TEST_TIME 85 9850
TEST_TIME 86 9789
TEST_TIME 87 9704
TEST_TIME 88 9766
TEST_TIME 89 9826
TEST_TIME 90 9845
TEST_TIME 91 9815
TEST_TIME 92 9894
TEST_TIME 93 9901
TEST_TIME 94 9933
TEST_TIME 95 9909
TEST_TIME 96 9896
TEST_TIME 97 9937
TEST_TIME 98 9965
TEST_TIME 99 10020
TEST_TIME 100 10060
TEST_TIME 101 10021
TEST_TIME 102 10081
TEST_TIME 103 10057
TEST_TIME 104 9983
TEST_TIME 105 9919
TEST_TIME 106 10004
TEST_TIME 107 9980
TEST_TIME 108 9935
TEST_TIME 109 9894
TEST_TIME 110 9889
TEST_TIME 111 9853
TEST_TIME 112 9844
TEST_TIME 113 9859
TEST_TIME 114 9823
TEST_TIME 115 9803
TEST_TIME 116 9840
TEST_TIME 117 9841
TEST_TIME 118 9822
TEST_TIME 119 9836
TEST_TIME 120 9843

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-05-2022 22:39:30 to 26-05-2022 22:39:33.

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 1627586 1627586 0 0.00 0 34902 5 0 0
doc_list_biud 1627581 1627581 0 0.00 0 35272 28 0 0
sp_add_invoice_to_stock 5291 5154 137 2.58 38 186433 17030 0 137
sp_cancel_adding_invoice 6223 6223 0 0.00 5 92058 797 0 0
sp_cancel_client_order 1738 1738 0 0.00 4 11114 360 0 0
sp_cancel_customer_reserve 606273 606273 0 0.00 1 35275 60 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 6032 109 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 3709 78 0 0
sp_cancel_supplier_invoice 6878 6864 14 0.20 2 19728 750 0 14
sp_cancel_supplier_order 405 405 0 0.00 9 995055 112590 0 0
sp_cancel_write_off 11627 11627 0 0.00 1 31484 105 0 0
sp_client_order 20639 20639 0 0.00 3 15476 338 0 0
sp_customer_reserve 484867 484867 0 0.00 0 62827 163 0 0
sp_fill_shopping_cart 31758 31758 0 0.00 1 11347 38 0 0
sp_fill_shopping_cart_clo_res 484863 484863 0 0.00 0 20142 13 0 0
sp_get_clo_for_invoice 5148 5148 0 0.00 0 41242 271 0 0
sp_kill_cost_storno 18993 18993 0 0.00 0 15428 51 0 0
sp_kill_qstorno_ret_qs2qd 1231442 1231442 0 0.00 0 35268 32 0 0
sp_kill_qty_storno 648330 648330 0 0.00 0 35271 70 0 0
sp_lock_selected_doc 21038 21038 0 0.00 0 10041 5 0 0
sp_make_cost_storno 28464 28464 0 0.00 0 12167 53 0 0
sp_multiply_rows_for_pdistr 28464 28464 0 0.00 0 12995 29 0 0
sp_multiply_rows_for_qdistr 20639 20639 0 0.00 0 14872 155 0 0
sp_pay_from_customer 7262 7262 0 0.00 4 13054 115 0 0
sp_pay_to_supplier 5998 5998 0 0.00 4 13563 139 0 0
sp_payment_common 13259 13259 0 0.00 2 11061 25 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 11103 330 0 0
sp_qd_handle_on_invoice_upd_sts 11371 11371 0 0.00 0 29524 357 0 0
sp_qd_handle_on_reserve_upd_sts 10052 10052 0 0.00 0 16613 64 0 0
sp_reserve_write_off 10056 10056 0 0.00 7 16645 177 0 0
sp_supplier_invoice 5529 5529 0 0.00 6 66074 3311 0 0
sp_supplier_order 5605 5605 0 0.00 5 66361 1334 0 0
srv_aggregate_perf_data 89 89 0 0.00 2982 477871 24337 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1509 137184 46267 0 0
srv_find_qd_qs_mism 1130763 1130763 0 0.00 0 34901 4 0 0
srv_make_invnt_saldo 582 582 0 0.00 26 18827 1045 0 0
srv_make_money_saldo 814 814 0 0.00 1 3549 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 162598 656619 363117 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5154 5154 0 0.00 0 17578 159 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 2113 105 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 4381 17 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 2507 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 852 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 1738 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1609 15 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 23109 1774 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 1464 5 0 0
t$perf-norm:sp_client_order 20639 20639 0 0.00 0 10893 19 0 0
t$perf-norm:sp_customer_reserve 1045 1045 0 0.00 0 809 5 0 0
t$perf-norm:sp_pay_from_customer 7262 7262 0 0.00 0 22416 12 0 0
t$perf-norm:sp_pay_to_supplier 5998 5998 0 0.00 0 10476 11 0 0
t$perf-norm:sp_reserve_write_off 10056 10056 0 0.00 0 9794 8 0 0
t$perf-norm:sp_supplier_invoice 5529 5529 0 0.00 0 3414 17 0 0
t$perf-norm:sp_supplier_order 5605 5605 0 0.00 0 2482 15 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 171 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 9873 183 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 1000 22 0 0
t$perf-norm:srv_make_money_saldo 814 814 0 0.00 0 1979 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10568 10294 274 2.59 0 5565 16 0 274
v_all_customers 37768 37768 0 0.00 0 50 1 0 0
v_all_suppliers 31068 31068 0 0.00 0 18 1 0 0
v_all_wares 41274 41274 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 237 5 0 0
v_cancel_client_order 3476 3476 0 0.00 0 3269 4 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 5288 14 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2456 6 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 417 4 0 0
v_cancel_supplier_invoice 864 836 28 3.24 0 6175 18 0 28
v_cancel_supplier_order 810 810 0 0.00 0 1966 7 0 0
v_cancel_write_off 2012 2012 0 0.00 0 352 10 0 0
v_max_id_clo_ord 5605 5605 0 0.00 0 425 1 0 0
v_max_id_clo_res 1045 1045 0 0.00 0 614 5 0 0
v_max_id_ord_sup 5515 5515 0 0.00 0 3716 5 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 317 3 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 1581 4 0 0
v_min_id_clo_ord 5605 5605 0 0.00 0 11337 9 0 0
v_min_id_clo_res 1045 1045 0 0.00 0 7274 12 0 0
v_min_id_ord_sup 5515 5515 0 0.00 0 6506 5 0 0
v_min_non_paid_invoice 5998 5998 0 0.00 0 11058 17 0 0
v_min_non_paid_realizn 7261 7261 0 0.00 0 9681 19 0 0
v_our_firm 10492 10492 0 0.00 0 19 1 0 0
v_reserve_write_off 20104 20104 0 0.00 0 3887 5 0 0
x_lock_depdocs_on_canc_invoice 12669 12669 0 0.00 0 21453 135 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 13392 491 0 0
x_make_qstorno_1000_1200 5605 5605 0 0.00 2 66354 1247 0 0
x_make_qstorno_1200_2000 5529 5529 0 0.00 3 66066 3181 0 0
x_make_qstorno_2100_3300 484866 484866 0 0.00 0 62784 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-05-2022 22:39:34 to 26-05-2022 22:39:38.

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 33 8192000000 8819114096 8837308416 19620176 0.238 5 5 5 1 19902928 100016 182736
20:09:21 28356 8192000000 9899474064 10207977472 980235280 10.686 100 86 172 0 1008068624 5599520 22233824
20:10:54 18677 8192000000 9934309312 10253889536 1016955088 11.043 100 86 172 0 1047936704 8476832 22504784
20:12:27 24221 8192000000 9974773376 10309132288 1057535376 11.433 100 80 160 0 1087023616 9599360 19888880
20:13:55 16916 8192000000 10035139664 10383499264 1126200672 12.086 100 77 154 0 1155410192 10345840 18863680
20:15:15 5959 8192000000 10091469776 10449502208 1180167968 12.592 100 81 162 1 1211958928 12568736 19222224
20:16:23 4575 8192000000 10139759200 10507624448 1222104016 12.981 100 84 167 2 1254546944 13849168 18593760
20:17:31 10602 8192000000 10164140240 10543329280 1251510160 13.252 100 90 180 2 1287382048 15995968 19875920
20:18:44 2217 8192000000 10202374272 10585456640 1286840448 13.575 100 91 182 1 1321858048 14677856 20339744
20:19:48 35163 8192000000 10208172016 10599337984 1299928064 13.695 100 56 111 3 1319766144 7425920 12412160
20:21:24 3703 8192000000 10207509648 10615644160 1313325296 13.816 100 43 85 4 1330207696 6533392 10349008
20:22:31 5726 8192000000 10211592752 10628255744 1322524128 13.900 100 23 46 1 1338545360 11141136 4880096
20:23:41 5851 8192000000 10215654368 10634874880 1329378720 13.962 100 25 50 1 1341853392 6705520 5769152
20:24:49 63806 8192000000 10254586560 10654461952 1337939392 14.039 100 54 110 0 1357126144 5491248 13695504
20:26:54 8840 8192000000 10227220768 10651160576 1341311952 14.069 100 17 34 0 1351349568 5813616 4224000
20:28:03 8739 8192000000 10223674384 10647289856 1326497776 13.935 99 15 30 1 1335547712 5718432 3331504
20:29:16 76526 8192000000 10157940208 10575249408 1170420976 12.501 89 33 66 0 1183553952 5471968 7661008
20:31:34 8539 8192000000 10159076112 10561503232 1237948224 13.127 97 19 38 2 1248259632 5788048 4523360
20:32:43 5863 8192000000 10158584704 10563747840 1262889440 13.356 100 21 41 2 1273100064 5575424 4635200
20:33:54 8378 8192000000 10151473456 10556567552 1254419008 13.279 99 27 54 4 1266959696 6348016 6192672
20:35:02 25479 8192000000 10148107952 10562842624 1262725856 13.355 99 22 44 1 1273948688 6460928 4761904
20:36:28 4501 8192000000 10192981968 10601918464 1294548080 13.646 100 29 59 1 1307651792 6054736 7048976
20:37:33 11608 8192000000 10188287168 10605555712 1300108528 13.696 100 23 46 1 1315366496 9824032 5433936
20:38:51 24264 8192000000 10238502800 10643111936 1327689488 13.946 100 59 113 1 1348531152 8082528 12759136
20:40:20 89352 8192000000 10256575216 10673045504 1348220480 14.131 100 36 73 0 1367694240 11064224 8409536
20:42:55 34034 8192000000 10266415312 10693742592 1361655920 14.252 100 31 63 1 1383043008 14069296 7317792
20:44:36 23391 8192000000 10289690352 10710024192 1377862960 14.397 100 42 83 2 1404609104 16462400 10283744
20:46:05 3326 8192000000 10306743024 10724974592 1386903424 14.478 100 56 108 1 1418344448 18171648 13269376
20:47:19 3857 8192000000 10312383728 10736832512 1391687600 14.521 100 54 105 1 1424680496 19459024 13533872
20:48:32 14666 8192000000 10330248240 10750099456 1384171056 14.454 99 57 114 0 1421508560 23247568 14089936
20:50:02 5730 8192000000 10342924240 10768695296 1408691600 14.672 100 66 131 1 1454914800 30599552 15623648
20:51:11 5744 8192000000 10341595120 10767863808 1408356192 14.669 100 59 115 1 1449203056 26946560 13900304
20:52:23 11651 8192000000 10343827936 10778439680 1420375552 14.776 100 50 100 1 1457921984 24974752 12571680
20:53:45 7659 8192000000 10368617360 10783006720 1419808352 14.771 100 77 149 1 1466414832 29415712 17190768
20:54:58 3614 8192000000 10371492832 10797928448 1422173280 14.792 99 64 128 2 1470037040 31513712 16350048
20:56:06 4819 8192000000 10362188864 10781097984 1412516688 14.706 100 74 144 3 1467986208 37542672 17926848
20:57:15 1508 8192000000 10342973312 10754686976 1386115888 14.471 99 69 138 2 1442803824 39948112 16739824
20:58:20 3785 8192000000 10341925408 10755928064 1382334272 14.437 99 57 112 0 1435289568 38993760 13961536
20:59:29 3681 8192000000 10325137152 10742190080 1375344496 14.375 100 51 99 1 1428960048 40092800 13522752
21:00:38 3156 8192000000 10330560880 10739474432 1367262832 14.303 100 77 153 1 1435534720 48375184 19896704
21:01:46 7718 8192000000 10339087824 10759696384 1372842080 14.353 100 45 90 0 1441531328 55699632 12989616
21:03:02 7250 8192000000 10337593600 10761125888 1375261504 14.374 100 49 98 0 1439949040 51511920 13175616
21:04:14 13513 8192000000 10332446464 10762280960 1380545840 14.421 100 32 63 1 1439333472 49666016 9121616
21:05:32 31611 8192000000 10324379472 10767958016 1367756400 14.307 99 23 46 0 1423945520 49560704 6628416
21:07:15 53700 8192000000 10293163680 10731069440 1337006400 14.030 98 18 34 0 1380879232 39063568 4809264
21:09:38 57471 8192000000 10296685520 10752413696 1360787840 14.244 100 8 16 0 1392174832 29293376 2093616
21:11:40 98671 8192000000 10275560784 10712285184 1311623648 13.801 96 24 47 1 1326358144 8795776 5938720
21:14:19 77348 8192000000 10283821984 10737528832 1350255872 14.150 99 6 11 1 1367081216 15392544 1432800
21:16:39 85179 8192000000 10289268832 10748182528 1350324720 14.150 98 13 26 0 1376086160 23032432 2729008
21:19:04 57791 8192000000 10288778144 10739253248 1362564352 14.260 99 4 7 1 1379297200 16229440 503408
21:21:03 62754 8192000000 10301714288 10732482560 1375962528 14.380 100 35 69 4 1391739744 7867520 7909696
21:23:10 41972 8192000000 10308149888 10741088256 1382057776 14.435 100 42 85 0 1402991728 10719040 10214912
21:24:53 67333 8192000000 10302406352 10758180864 1390099536 14.507 100 34 66 2 1406775152 9261408 7414208
21:27:21 60470 8192000000 10309202112 10756501504 1367384352 14.304 99 28 56 2 1388679504 14868256 6426896
21:29:27 31266 8192000000 10292445728 10753282048 1373335968 14.357 99 7 14 0 1387738864 12876544 1526352
21:30:58 24991 8192000000 10300392736 10751627264 1387308144 14.482 100 22 44 1 1404754784 12147008 5299632
21:32:30 16967 8192000000 10303240016 10749505536 1379648496 14.413 100 52 103 2 1406472976 14191504 12632976
21:33:49 39603 8192000000 10296093616 10758397952 1392733360 14.530 100 10 21 0 1405365136 10306992 2324784
21:35:31 25838 8192000000 10297917776 10761719808 1393528256 14.537 100 20 40 1 1414237376 16112272 4596848
21:37:00 87792 8192000000 10272561120 10734383104 1356198160 14.203 98 21 43 0 1368056512 7017536 4840816
21:39:28 72918 8192000000 10267628864 10734194688 1337320928 14.033 97 16 32 0 1348345184 7857472 3166784
21:41:49 75638 8192000000 10290367184 10761306112 1374092176 14.364 99 16 32 0 1391111920 13580688 3439056
21:44:19 56424 8192000000 10268893440 10735661056 1364106672 14.274 99 9 18 0 1382214192 16441616 1665904
21:46:17 34911 8192000000 10270690752 10722709504 1288510256 13.591 93 34 68 0 1304339024 8442400 7386368
21:47:53 32630 8192000000 10266901024 10723774464 1338188048 14.041 98 30 60 1 1358658016 13263968 7206000
21:49:27 67825 8192000000 10285018224 10735755264 1330374320 13.971 97 19 38 0 1346981296 11602064 5004912
21:51:35 71922 8192000000 10281859072 10740518912 1351458032 14.161 98 14 28 0 1371821664 16801904 3561728
21:53:50 34473 8192000000 10276116208 10720702464 1329716000 13.965 97 29 58 1 1348974208 12580416 6677792
21:55:27 128898 8192000000 10259492480 10710310912 1297648288 13.674 95 26 52 1 1319622432 15889712 6084432
21:58:41 51306 8192000000 10259551152 10719457280 1347630464 14.126 99 22 44 0 1362384672 9472256 5281952
22:00:35 71355 8192000000 10254102544 10716270592 1321661712 13.892 97 13 26 0 1335467856 10582720 3223424
22:03:18 59864 8192000000 10278870224 10725920768 1350158784 14.149 98 20 40 1 1367343472 12121552 5063136
22:05:20 87352 8192000000 10271229872 10743709696 1371553952 14.341 100 11 22 0 1388338064 14026976 2757136
22:08:00 84911 8192000000 10288185968 10740318208 1352113312 14.166 98 18 36 2 1363557760 7840112 3604336
22:10:26 86178 8192000000 10295359440 10758049792 1380917056 14.425 99 16 32 1 1395991008 11169312 3904640
22:12:56 32352 8192000000 10320533344 10753687552 1391844016 14.522 100 36 72 0 1409174464 8942096 8388352
22:14:32 27784 8192000000 10302955664 10754822144 1387359392 14.482 100 37 72 1 1408970000 13368976 8241632
22:16:02 53268 8192000000 10308913552 10757603328 1364620768 14.279 98 14 28 0 1382063360 14374304 3068288
22:17:55 92743 8192000000 10295351024 10741211136 1352632944 14.171 97 19 38 0 1369165472 11918416 4614112
22:20:49 137184 8192000000 10313840016 10769805312 1371709808 14.342 98 18 36 1 1389995408 13419520 4866080
22:24:06 51668 8192000000 10302388912 10784419840 1391018656 14.515 100 12 24 1 1416468576 22447312 3002608
22:26:29 64642 8192000000 10299804128 10753212416 1351201392 14.158 96 17 34 1 1364908704 9773568 3933744
22:28:37 73784 8192000000 10281445936 10739855360 1344375312 14.097 97 14 28 0 1365856704 17937328 3544064
22:30:56 74153 8192000000 10272293184 10725744640 1314601328 13.828 95 16 32 0 1329468000 11136656 3730016
22:33:22 65586 8192000000 10263961392 10726297600 1337596864 14.036 98 20 40 0 1359100944 17120880 4383200
22:35:42 33453 8192000000 10276641888 10725531648 1354790672 14.191 99 29 56 4 1375381168 13788240 6802256
22:37:16 44303 8192000000 10264561600 10718879744 1334983856 14.012 98 19 39 0 1360859200 21636288 4239056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-05-2022 22:39:56 to 26-05-2022 22:39:58.

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          239536
MON$OLDEST_ACTIVE               240030
MON$OLDEST_SNAPSHOT             240030
MON$NEXT_TRANSACTION            240030
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                       2057312
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.33596 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33596 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33596 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33596 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 ---
:96: 
:97: mscs-test-161.budzdorov.ru    Thu May 26 22:38:21 2022
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Thu May 26 22:38:25 2022
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Thu May 26 22:38:26 2022
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Thu May 26 22:38:26 2022
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Thu May 26 22:38:26 2022
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:115: 
:116: 
:117: mscs-test-161.budzdorov.ru    Thu May 26 22:38:26 2022
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Thu May 26 22:38:26 2022
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Thu May 26 22:38:26 2022
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Thu May 26 22:38:26 2022
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:131: 
--- end of diff output ---

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

26.05.22 22:39:59. 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-26 22:40:01 No severe PSQL-related problems occured
26.05.22 22:40:03. End of report.