Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.04.2022 19: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 16785796 kB
MemAvailable 31489996 kB
Buffers 0 kB
Cached 14906968 kB
SwapCached 2688 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-04-14 19:38:18
DTS_END                         2022-04-14 19:38:18.5000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54822
TRN_ID                          234604
ATT_ID                          613
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: 9121.42 23522 1094571
customer order: creation 166.35 365 19962
customer order: refuse 14.50 345 1740
order to supplier: creation 44.58 1345 5349
order to supplier: removal 3.31 95088 397
invoice (draft): creation 45.30 3494 5436
invoice (draft): removal 52.81 725 6337
invoice accept: apply 40.58 17785 4869
invoice accept: cancel 47.56 830 5707
customer reserve: creation 3731.28 176 447754
customer reserve: removal 4679.00 53 561480
realization accept: apply 81.43 186 9772
realization accept: cancel 87.93 112 10551
payment from customer: creation 58.92 133 7070
payment from customer: removal 5.43 82 651
payment to supplier: creation 47.58 128 5710
payment to supplier: removal 3.17 91 380
service: total inventory turnovers 4.53 1185 544
service: total monetary turnovers 6.33 37 760
service: aggregate perf. data 0.82 22247 98
service: refresh index statistics 0.03 326033 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-04-2022 19:39:00 to 14-04-2022 19:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4556
TEST_TIME 2 5229
TEST_TIME 3 4857
TEST_TIME 4 5265
TEST_TIME 5 4997
TEST_TIME 6 5348
TEST_TIME 7 5232
TEST_TIME 8 5330
TEST_TIME 9 5829
TEST_TIME 10 6140
TEST_TIME 11 6148
TEST_TIME 12 6168
TEST_TIME 13 6744
TEST_TIME 14 7063
TEST_TIME 15 7650
TEST_TIME 16 8201
TEST_TIME 17 8782
TEST_TIME 18 8723
TEST_TIME 19 9036
TEST_TIME 20 9197
TEST_TIME 21 9808
TEST_TIME 22 10078
TEST_TIME 23 9977
TEST_TIME 24 10181
TEST_TIME 25 10068
TEST_TIME 26 10027
TEST_TIME 27 10188
TEST_TIME 28 10404
TEST_TIME 29 10440
TEST_TIME 30 10143
TEST_TIME 31 9996
TEST_TIME 32 10374
TEST_TIME 33 10198
TEST_TIME 34 10143
TEST_TIME 35 10222
TEST_TIME 36 10038
TEST_TIME 37 9878
TEST_TIME 38 9911
TEST_TIME 39 9748
TEST_TIME 40 9669
TEST_TIME 41 9619
TEST_TIME 42 9537
TEST_TIME 43 9474
TEST_TIME 44 9472
TEST_TIME 45 9444
TEST_TIME 46 9322
TEST_TIME 47 9297
TEST_TIME 48 9221
TEST_TIME 49 9126
TEST_TIME 50 9097
TEST_TIME 51 9054
TEST_TIME 52 9005
TEST_TIME 53 8990
TEST_TIME 54 8923
TEST_TIME 55 9126
TEST_TIME 56 9047
TEST_TIME 57 9207
TEST_TIME 58 9239
TEST_TIME 59 9185
TEST_TIME 60 9155
TEST_TIME 61 9229
TEST_TIME 62 9204
TEST_TIME 63 9246
TEST_TIME 64 9272
TEST_TIME 65 9348
TEST_TIME 66 9336
TEST_TIME 67 9342
TEST_TIME 68 9431
TEST_TIME 69 9475
TEST_TIME 70 9412
TEST_TIME 71 9316
TEST_TIME 72 9284
TEST_TIME 73 9282
TEST_TIME 74 9297
TEST_TIME 75 9285
TEST_TIME 76 9245
TEST_TIME 77 9241
TEST_TIME 78 9183
TEST_TIME 79 9218
TEST_TIME 80 9116
TEST_TIME 81 9063
TEST_TIME 82 9050
TEST_TIME 83 9019
TEST_TIME 84 9065
TEST_TIME 85 9047
TEST_TIME 86 9039
TEST_TIME 87 9133
TEST_TIME 88 9104
TEST_TIME 89 9113
TEST_TIME 90 9151
TEST_TIME 91 9260
TEST_TIME 92 9236
TEST_TIME 93 9360
TEST_TIME 94 9328
TEST_TIME 95 9320
TEST_TIME 96 9451
TEST_TIME 97 9384
TEST_TIME 98 9294
TEST_TIME 99 9282
TEST_TIME 100 9276
TEST_TIME 101 9259
TEST_TIME 102 9229
TEST_TIME 103 9179
TEST_TIME 104 9158
TEST_TIME 105 9143
TEST_TIME 106 9105
TEST_TIME 107 9089
TEST_TIME 108 9050
TEST_TIME 109 9064
TEST_TIME 110 9072
TEST_TIME 111 9079
TEST_TIME 112 9086
TEST_TIME 113 9098
TEST_TIME 114 9086
TEST_TIME 115 9119
TEST_TIME 116 9152
TEST_TIME 117 9171
TEST_TIME 118 9173
TEST_TIME 119 9190
TEST_TIME 120 9126

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-04-2022 19:39:03 to 14-04-2022 19:39:07.

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 1509309 1509309 0 0.00 0 49411 6 0 0
doc_list_biud 1509308 1509308 0 0.00 0 35689 25 0 0
sp_add_invoice_to_stock 4969 4869 100 2.01 36 150862 17786 0 100
sp_cancel_adding_invoice 5707 5707 0 0.00 4 82692 830 0 0
sp_cancel_client_order 1740 1740 0 0.00 4 11165 346 0 0
sp_cancel_customer_reserve 561480 561480 0 0.00 1 35693 53 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 2 4329 82 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 3382 91 0 0
sp_cancel_supplier_invoice 6345 6337 8 0.12 2 31750 725 0 8
sp_cancel_supplier_order 397 397 0 0.00 6 911902 95088 0 0
sp_cancel_write_off 10551 10551 0 0.00 1 13217 113 0 0
sp_client_order 19962 19962 0 0.00 3 39524 365 0 0
sp_customer_reserve 447754 447754 0 0.00 0 83990 176 0 0
sp_fill_shopping_cart 30739 30739 0 0.00 1 18346 42 0 0
sp_fill_shopping_cart_clo_res 447740 447740 0 0.00 0 17842 14 0 0
sp_get_clo_for_invoice 4855 4855 0 0.00 0 23736 323 0 0
sp_kill_cost_storno 17289 17289 0 0.00 0 9345 55 0 0
sp_kill_qstorno_ret_qs2qd 1140245 1140245 0 0.00 0 35677 29 0 0
sp_kill_qty_storno 600838 600838 0 0.00 0 35688 63 0 0
sp_lock_selected_doc 20296 20296 0 0.00 0 6954 7 0 0
sp_make_cost_storno 27407 27407 0 0.00 0 49398 62 0 0
sp_multiply_rows_for_pdistr 27407 27407 0 0.00 0 14700 28 0 0
sp_multiply_rows_for_qdistr 19962 19962 0 0.00 0 12838 152 0 0
sp_pay_from_customer 7070 7070 0 0.00 4 17786 133 0 0
sp_pay_to_supplier 5710 5710 0 0.00 5 15540 129 0 0
sp_payment_common 12780 12780 0 0.00 2 10938 41 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 11155 314 0 0
sp_qd_handle_on_invoice_upd_sts 10561 10561 0 0.00 0 27786 322 0 0
sp_qd_handle_on_reserve_upd_sts 9772 9772 0 0.00 0 13287 57 0 0
sp_reserve_write_off 9772 9772 0 0.00 6 15266 187 0 0
sp_supplier_invoice 5436 5436 0 0.00 7 74557 3495 0 0
sp_supplier_order 5349 5349 0 0.00 6 35287 1345 0 0
srv_aggregate_perf_data 98 98 0 0.00 2663 465829 22247 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1476 143058 47585 0 0
srv_find_qd_qs_mism 1048172 1048172 0 0.00 0 9491 5 0 0
srv_make_invnt_saldo 544 544 0 0.00 22 93968 1185 0 0
srv_make_money_saldo 760 760 0 0.00 1 8695 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 253106 462433 326034 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4869 4869 0 0.00 0 11988 176 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 4246 101 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 4934 16 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 2540 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 9239 30 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 2152 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 4070 26 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 25440 1834 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 3631 8 0 0
t$perf-norm:sp_client_order 19962 19962 0 0.00 0 15318 20 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 5083 12 0 0
t$perf-norm:sp_pay_from_customer 7070 7070 0 0.00 0 5313 14 0 0
t$perf-norm:sp_pay_to_supplier 5710 5710 0 0.00 0 14795 17 0 0
t$perf-norm:sp_reserve_write_off 9772 9772 0 0.00 0 14812 15 0 0
t$perf-norm:sp_supplier_invoice 5436 5436 0 0.00 0 5732 17 0 0
t$perf-norm:sp_supplier_order 5349 5349 0 0.00 0 6441 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 372 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 195 5 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 1048 22 0 0
t$perf-norm:srv_make_money_saldo 760 760 0 0.00 0 923 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9908 9708 200 2.01 0 6079 19 0 200
v_all_customers 36910 36910 0 0.00 0 31 1 0 0
v_all_suppliers 29980 29980 0 0.00 0 40 1 0 0
v_all_wares 39924 39924 0 0.00 0 111 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 1516 10 0 0
v_cancel_client_order 3480 3480 0 0.00 0 1991 6 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 1592 7 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 1890 5 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 684 6 0 0
v_cancel_supplier_invoice 870 854 16 1.83 0 1585 16 0 16
v_cancel_supplier_order 794 794 0 0.00 0 3629 10 0 0
v_cancel_write_off 1938 1938 0 0.00 0 3833 16 0 0
v_max_id_clo_ord 5348 5348 0 0.00 0 287 1 0 0
v_max_id_clo_res 975 975 0 0.00 0 3098 13 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 6653 5 0 0
v_max_non_paid_invoice 1497 1497 0 0.00 0 2414 6 0 0
v_max_non_paid_realizn 3571 3571 0 0.00 0 3367 4 0 0
v_min_id_clo_ord 5348 5348 0 0.00 0 1889 6 0 0
v_min_id_clo_res 975 975 0 0.00 0 2697 7 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 4683 7 0 0
v_min_non_paid_invoice 5710 5710 0 0.00 0 9104 32 0 0
v_min_non_paid_realizn 7070 7070 0 0.00 0 10930 34 0 0
v_our_firm 10012 10012 0 0.00 0 12 1 0 0
v_reserve_write_off 19544 19544 0 0.00 0 6284 7 0 0
x_lock_depdocs_on_canc_invoice 11617 11617 0 0.00 0 13962 110 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 23128 404 0 0
x_make_qstorno_1000_1200 5349 5349 0 0.00 1 35279 1258 0 0
x_make_qstorno_1200_2000 5436 5436 0 0.00 4 74392 3346 0 0
x_make_qstorno_2100_3300 447754 447754 0 0.00 0 83989 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-04-2022 19:39:08 to 14-04-2022 19:39:12.

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
17:08:22 23 8192000000 8807719648 8821837824 11331360 0.138 4 1 2 0 11441952 62448 48144
17:09:23 29754 8192000000 9895633696 10203906048 967544016 10.563 100 81 162 0 994539488 5223072 21772400
17:10:58 24243 8192000000 9943928944 10261635072 1025765824 11.128 100 90 180 0 1056532176 7526320 23240032
17:12:28 15856 8192000000 9984966256 10317705216 1067278144 11.526 100 88 176 1 1098081360 8983648 21819568
17:13:47 9377 8192000000 10047551392 10398318592 1136597136 12.184 100 82 163 2 1165504320 10067424 18839760
17:15:00 11062 8192000000 10098785312 10461204480 1185745920 12.644 100 84 168 2 1217784624 13306176 18732528
17:16:15 6680 8192000000 10124923632 10492882944 1212919040 12.896 100 78 156 1 1243206912 12877776 17410096
17:17:23 6964 8192000000 10149091360 10527453184 1242890624 13.173 100 71 142 2 1271953168 13041376 16021168
17:18:33 1874 8192000000 10189907152 10571022336 1276244384 13.479 100 95 190 2 1311804304 14022336 21537584
17:19:36 1743 8192000000 10220986960 10605096960 1297622544 13.674 100 93 188 3 1331800768 13409760 20768464
17:20:39 1380 8192000000 10226090784 10612039680 1307603456 13.764 100 87 174 1 1337890656 10617408 19669792
17:21:41 8080 8192000000 10196695632 10614198272 1318890272 13.867 100 29 58 0 1332178816 7102496 6186048
17:22:51 8257 8192000000 10211213808 10628362240 1327372400 13.943 100 19 38 0 1337066864 5074112 4620352
17:24:04 34051 8192000000 10246925216 10651238400 1333401632 13.998 100 63 124 0 1355486592 6254608 15830352
17:25:39 5436 8192000000 10234797920 10652581888 1340175584 14.059 100 36 72 1 1355554912 6983024 8396304
17:26:46 11234 8192000000 10228821216 10649759744 1337752688 14.037 100 30 59 1 1350793280 6002720 7037872
17:27:58 8865 8192000000 10225866368 10647937024 1336191536 14.023 100 18 35 1 1346096016 5680208 4224272
17:29:11 4882 8192000000 10212720384 10622033920 1296987760 13.668 98 25 50 2 1309118304 5377296 6753248
17:30:18 7284 8192000000 10137108112 10534182912 1190079312 12.684 94 31 62 0 1203983216 4909024 8994880
17:31:27 55196 8192000000 10131457808 10534547456 1197018592 12.749 95 20 40 2 1206869280 5031248 4819440
17:33:23 9905 8192000000 10158829360 10561331200 1262337200 13.351 100 25 50 0 1274104656 5715856 6051600
17:34:38 8145 8192000000 10174705872 10578022400 1267589056 13.400 99 24 48 1 1278373232 5113840 5670336
17:35:49 71217 8192000000 10195874640 10602635264 1293405920 13.635 100 23 46 0 1303833904 5269760 5158224
17:38:04 6991 8192000000 10199886000 10614820864 1308347664 13.771 100 24 48 2 1323515184 10107536 5059984
17:39:20 14302 8192000000 10239244672 10645360640 1332126544 13.986 100 57 111 3 1357530784 12818256 12585984
17:40:39 30937 8192000000 10266246864 10680442880 1356646336 14.207 100 46 88 3 1383094864 15822304 10626224
17:42:15 15246 8192000000 10282683520 10700861440 1371409264 14.340 100 39 75 3 1397478480 17388992 8680224
17:43:36 36115 8192000000 10288689536 10716532736 1379843360 14.415 100 33 66 1 1408147248 19592976 8710912
17:45:23 19066 8192000000 10314213872 10738302976 1393068080 14.533 100 47 94 0 1423477248 19142048 11267120
17:46:45 7827 8192000000 10322752416 10750840832 1405767728 14.646 100 43 86 2 1439814208 23175328 10871152
17:47:57 16342 8192000000 10337749392 10757763072 1409634256 14.681 100 65 129 1 1448274928 23502000 15138672
17:49:16 5673 8192000000 10323223376 10738384896 1371176944 14.338 97 64 127 2 1415878400 29004480 15696976
17:50:25 1475 8192000000 10348974448 10760597504 1396252960 14.562 99 83 165 3 1449147712 32809264 20085488
17:51:32 2033 8192000000 10348653808 10759647232 1395808608 14.558 99 72 140 0 1443646752 30281824 17556320
17:52:44 4432 8192000000 10357344880 10767720448 1402058464 14.613 100 80 160 1 1460500608 38535104 19907040
17:53:54 3304 8192000000 10353133232 10770464768 1403246640 14.624 100 73 147 1 1458135520 36409264 18479616
17:55:01 6074 8192000000 10315467744 10736320512 1376536512 14.386 100 55 111 0 1423565248 32487360 14541376
17:56:21 17375 8192000000 10305876304 10733961216 1366233584 14.293 99 24 48 0 1402166752 29110048 6823120
17:57:41 4943 8192000000 10313078160 10729398272 1365942800 14.291 100 66 132 0 1416555216 33500144 17112272
17:58:56 9772 8192000000 10310278624 10740576256 1361998816 14.255 99 39 76 2 1409295440 37140672 10155952
18:00:15 29098 8192000000 10279964960 10712002560 1334005680 14.003 98 23 46 4 1367066720 26517008 6544032
18:02:01 6385 8192000000 10313051472 10740367360 1366501376 14.296 100 58 116 0 1412022208 31387984 14132848
18:03:13 12085 8192000000 10305974592 10727993344 1358703216 14.226 99 52 104 0 1399850672 27198848 13948608
18:04:34 18421 8192000000 10298402704 10735923200 1373488640 14.358 100 25 48 3 1406147696 26270608 6388448
18:06:16 108798 8192000000 10271313536 10723098624 1327871584 13.948 98 14 28 2 1358765056 27107920 3785552
18:09:20 41736 8192000000 10277928304 10710126592 1339884208 14.056 99 36 73 0 1376999360 28311808 8803344
18:11:10 19305 8192000000 10281484832 10711007232 1363910528 14.272 100 49 97 2 1388280112 12775808 11593776
18:12:40 101883 8192000000 10285188512 10723340288 1372999296 14.354 100 24 47 0 1396035840 17412208 5624336
18:15:27 51840 8192000000 10289452000 10739941376 1382438672 14.438 100 19 38 1 1401575584 14790752 4346160
18:17:20 64862 8192000000 10273836368 10718998528 1357503456 14.215 98 27 54 1 1372416368 9050304 5862608
18:19:54 67816 8192000000 10278616608 10732638208 1380872880 14.424 100 12 24 0 1392882928 9166128 2843920
18:22:06 86986 8192000000 10273010448 10731003904 1370948640 14.336 99 11 23 4 1383255456 9535712 2771104
18:24:42 76681 8192000000 10286650064 10742616064 1388124736 14.489 100 38 77 1 1405317280 8685936 8506608
18:27:04 7373 8192000000 10299660496 10746167296 1397069536 14.569 100 42 83 2 1413715216 6630176 10015504
18:28:25 58908 8192000000 10306667984 10762944512 1390129984 14.507 99 42 84 0 1415694880 15882032 9682864
18:30:33 62944 8192000000 10304652912 10759462912 1393129792 14.534 99 12 24 0 1414180560 18150256 2900512
18:32:36 66000 8192000000 10297445776 10772021248 1396186720 14.561 100 15 30 0 1424434416 24238528 4009168
18:34:47 92839 8192000000 10306272560 10778902528 1387540768 14.484 99 11 22 1 1404397280 14393712 2462800
18:37:23 33068 8192000000 10308815664 10752913408 1389365344 14.500 99 44 86 1 1407245296 7986736 9893216
18:38:56 78579 8192000000 10300032992 10760904704 1363711232 14.271 97 15 30 0 1380557264 13706560 3139472
18:41:15 143058 8192000000 10319599552 10774228992 1335979168 14.021 95 23 46 0 1361352432 20782784 4590480
18:44:45 47123 8192000000 10317914848 10755981312 1370019456 14.327 98 26 52 0 1388215104 11835616 6360032
18:46:36 123727 8192000000 10287231440 10736111616 1308260656 13.770 94 17 34 1 1328393584 16303840 3829088
18:49:52 60642 8192000000 10256981424 10692882432 1302672240 13.720 95 30 60 0 1318606496 8758400 7175856
18:51:53 72323 8192000000 10260372896 10715488256 1318000128 13.859 97 22 44 1 1342763152 20379584 4383440
18:54:06 47910 8192000000 10260477360 10715860992 1355280480 14.195 99 10 20 1 1367177824 9671088 2226256
18:55:55 80552 8192000000 10222970432 10665267200 1276733088 13.483 94 14 28 0 1289285904 9158368 3394448
18:58:20 53861 8192000000 10237685328 10675654656 1304137440 13.733 97 15 30 0 1313504608 6062128 3305040
19:00:21 57480 8192000000 10213543216 10659106816 1238406176 13.132 92 9 19 1 1246838896 6899600 1533120
19:02:19 55925 8192000000 10230204208 10677772288 1312576736 13.809 98 19 38 1 1328445904 11064192 4804976
19:04:15 40390 8192000000 10260562384 10703843328 1353977584 14.183 100 18 36 1 1369035408 10545808 4512016
19:06:06 38193 8192000000 10263490880 10702344192 1330365872 13.970 98 25 50 2 1353332096 16976304 5989920
19:07:54 73576 8192000000 10256984976 10704531456 1316496368 13.845 97 21 43 0 1339129552 18175408 4457776
19:10:14 57402 8192000000 10282386432 10731036672 1364784240 14.280 99 20 40 0 1383163264 14183088 4195936
19:12:16 31398 8192000000 10287528480 10727448576 1355115136 14.193 98 14 28 0 1365884640 7623296 3146208
19:13:52 35661 8192000000 10326224048 10776006656 1386417536 14.474 99 34 67 0 1411333440 17582560 7333344
19:15:39 66405 8192000000 10309917840 10754527232 1388180032 14.490 99 29 56 1 1407513616 12625360 6708224
19:17:50 38490 8192000000 10283977264 10742996992 1379523472 14.412 99 27 54 0 1394744800 9445872 5775456
19:19:31 68218 8192000000 10276019840 10737397760 1365980176 14.291 98 11 20 1 1374006512 5792864 2233472
19:22:05 91991 8192000000 10280984960 10749902848 1386010528 14.470 99 15 30 2 1400261376 10004064 4246784
19:24:51 58357 8192000000 10292640960 10759335936 1402165888 14.614 100 14 28 0 1415082896 9735584 3181424
19:27:45 101262 8192000000 10297178912 10754879488 1353206512 14.176 96 12 24 0 1369693760 13228768 3258480
19:30:34 35558 8192000000 10304430496 10759946240 1387993104 14.488 99 24 48 0 1405587136 11674112 5919920
19:32:14 51716 8192000000 10315144368 10758057984 1371116272 14.337 98 16 32 0 1390699984 15714352 3869360
19:34:11 95110 8192000000 10293776736 10750369792 1335834512 14.020 96 16 32 1 1356930368 17673040 3422816
19:36:49 43342 8192000000 10298018384 10759581696 1399261136 14.588 100 17 34 1 1413512800 10631984 3619680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-04-2022 19:39:14 to 14-04-2022 19:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-04-2022 19:39:29 to 14-04-2022 19:39:31.

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          234032
MON$OLDEST_ACTIVE               235427
MON$OLDEST_SNAPSHOT             235427
MON$NEXT_TRANSACTION            235427
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                       2009408
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:407: 
:408: mscs-test-161.budzdorov.ru    Thu Apr 14 19:38:19 2022
:409:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:410: 
:411: 
:412: mscs-test-161.budzdorov.ru    Thu Apr 14 19:38:20 2022
:413:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:414: 
:415: 
:416: mscs-test-161.budzdorov.ru    Thu Apr 14 19:38:20 2022
:417:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:418: 
:419: 
:420: mscs-test-161.budzdorov.ru    Thu Apr 14 19:38:24 2022
:421:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:422: 
--- end of diff output ---

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

14.04.22 19:39:32. 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-04-14 19:39:34 No severe PSQL-related problems occured
14.04.22 19:39:36. End of report.