Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.11.2021 19: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 16977608 kB
MemAvailable 31629564 kB
Buffers 0 kB
Cached 14733000 kB
SwapCached 0 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-16 19:37:53
DTS_END                         2021-11-16 19:37:53.3380
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/35706
TRN_ID                          234731
ATT_ID                          680
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: 9123.93 17468 1094872
customer order: creation 170.48 353 20457
customer order: refuse 14.75 328 1770
order to supplier: creation 44.68 1275 5362
order to supplier: removal 3.23 99120 388
invoice (draft): creation 44.10 3673 5292
invoice (draft): removal 50.83 746 6100
invoice accept: apply 41.53 19534 4984
invoice accept: cancel 45.53 786 5463
customer reserve: creation 3875.88 192 465105
customer reserve: removal 4543.72 56 545246
realization accept: apply 81.36 173 9763
realization accept: cancel 78.73 96 9448
payment from customer: creation 59.11 119 7093
payment from customer: removal 5.41 83 649
payment to supplier: creation 48.88 148 5866
payment to supplier: removal 3.48 79 417
service: total inventory turnovers 4.71 1142 565
service: total monetary turnovers 6.64 25 797
service: aggregate perf. data 0.86 19918 103
service: refresh index statistics 0.03 201506 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-11-2021 19:39:52 to 16-11-2021 19:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1557
TEST_TIME 2 2084
TEST_TIME 3 3173
TEST_TIME 4 3508
TEST_TIME 5 3890
TEST_TIME 6 4707
TEST_TIME 7 4839
TEST_TIME 8 5183
TEST_TIME 9 6149
TEST_TIME 10 6320
TEST_TIME 11 6399
TEST_TIME 12 6680
TEST_TIME 13 6714
TEST_TIME 14 7035
TEST_TIME 15 6970
TEST_TIME 16 7104
TEST_TIME 17 7316
TEST_TIME 18 7698
TEST_TIME 19 8285
TEST_TIME 20 8561
TEST_TIME 21 8684
TEST_TIME 22 8456
TEST_TIME 23 8298
TEST_TIME 24 9047
TEST_TIME 25 9000
TEST_TIME 26 9267
TEST_TIME 27 9581
TEST_TIME 28 9757
TEST_TIME 29 9942
TEST_TIME 30 9983
TEST_TIME 31 10136
TEST_TIME 32 10275
TEST_TIME 33 10043
TEST_TIME 34 9844
TEST_TIME 35 9752
TEST_TIME 36 9727
TEST_TIME 37 9777
TEST_TIME 38 9765
TEST_TIME 39 9614
TEST_TIME 40 9555
TEST_TIME 41 9548
TEST_TIME 42 9627
TEST_TIME 43 9507
TEST_TIME 44 9447
TEST_TIME 45 9330
TEST_TIME 46 9353
TEST_TIME 47 9321
TEST_TIME 48 9260
TEST_TIME 49 9285
TEST_TIME 50 9183
TEST_TIME 51 9154
TEST_TIME 52 8993
TEST_TIME 53 9148
TEST_TIME 54 9145
TEST_TIME 55 9073
TEST_TIME 56 9137
TEST_TIME 57 9193
TEST_TIME 58 9219
TEST_TIME 59 9206
TEST_TIME 60 9233
TEST_TIME 61 9308
TEST_TIME 62 9266
TEST_TIME 63 9337
TEST_TIME 64 9400
TEST_TIME 65 9373
TEST_TIME 66 9388
TEST_TIME 67 9491
TEST_TIME 68 9453
TEST_TIME 69 9458
TEST_TIME 70 9347
TEST_TIME 71 9339
TEST_TIME 72 9290
TEST_TIME 73 9238
TEST_TIME 74 9278
TEST_TIME 75 9201
TEST_TIME 76 9215
TEST_TIME 77 9172
TEST_TIME 78 9123
TEST_TIME 79 9114
TEST_TIME 80 9161
TEST_TIME 81 9135
TEST_TIME 82 9138
TEST_TIME 83 9111
TEST_TIME 84 9086
TEST_TIME 85 9254
TEST_TIME 86 9264
TEST_TIME 87 9244
TEST_TIME 88 9236
TEST_TIME 89 9284
TEST_TIME 90 9287
TEST_TIME 91 9317
TEST_TIME 92 9303
TEST_TIME 93 9209
TEST_TIME 94 9156
TEST_TIME 95 9154
TEST_TIME 96 9073
TEST_TIME 97 9022
TEST_TIME 98 9108
TEST_TIME 99 9090
TEST_TIME 100 9136
TEST_TIME 101 9111
TEST_TIME 102 9107
TEST_TIME 103 9101
TEST_TIME 104 9125
TEST_TIME 105 9138
TEST_TIME 106 9116
TEST_TIME 107 9141
TEST_TIME 108 9141
TEST_TIME 109 9159
TEST_TIME 110 9260
TEST_TIME 111 9299
TEST_TIME 112 9264
TEST_TIME 113 9240
TEST_TIME 114 9201
TEST_TIME 115 9182
TEST_TIME 116 9186
TEST_TIME 117 9152
TEST_TIME 118 9151
TEST_TIME 119 9130
TEST_TIME 120 9124

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-11-2021 19:39:56 to 16-11-2021 19:39:59.

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 1524268 1524268 0 0.00 0 26463 7 0 0
doc_list_biud 1524253 1524253 0 0.00 0 29721 26 0 0
sp_add_invoice_to_stock 5125 4984 141 2.75 31 172734 19534 0 141
sp_cancel_adding_invoice 5463 5463 0 0.00 5 75370 786 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 9978 328 0 0
sp_cancel_customer_reserve 545246 545246 0 0.00 1 25358 56 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 6249 84 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 3943 80 0 0
sp_cancel_supplier_invoice 6115 6100 15 0.24 2 12485 746 0 15
sp_cancel_supplier_order 388 388 0 0.00 6 834219 99121 0 0
sp_cancel_write_off 9448 9448 0 0.00 1 8632 96 0 0
sp_client_order 20457 20457 0 0.00 3 30511 353 0 0
sp_customer_reserve 465105 465105 0 0.00 0 139960 193 0 0
sp_fill_shopping_cart 31103 31103 0 0.00 1 15304 38 0 0
sp_fill_shopping_cart_clo_res 465105 465105 0 0.00 0 10415 13 0 0
sp_get_clo_for_invoice 4984 4984 0 0.00 0 37502 308 0 0
sp_kill_cost_storno 15977 15977 0 0.00 0 7228 54 0 0
sp_kill_qstorno_ret_qs2qd 1106428 1106428 0 0.00 0 25350 30 0 0
sp_kill_qty_storno 583151 583151 0 0.00 0 29719 66 0 0
sp_lock_selected_doc 20479 20479 0 0.00 0 7474 6 0 0
sp_make_cost_storno 27706 27706 0 0.00 0 26403 61 0 0
sp_multiply_rows_for_pdistr 27691 27691 0 0.00 0 26450 29 0 0
sp_multiply_rows_for_qdistr 20457 20457 0 0.00 0 14125 151 0 0
sp_pay_from_customer 7093 7093 0 0.00 4 15260 119 0 0
sp_pay_to_supplier 5866 5866 0 0.00 4 26471 148 0 0
sp_payment_common 12955 12955 0 0.00 1 11185 32 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 9972 300 0 0
sp_qd_handle_on_invoice_upd_sts 10438 10438 0 0.00 0 29716 327 0 0
sp_qd_handle_on_reserve_upd_sts 9761 9761 0 0.00 0 18712 61 0 0
sp_reserve_write_off 9763 9763 0 0.00 6 18753 174 0 0
sp_supplier_invoice 5292 5292 0 0.00 6 90829 3674 0 0
sp_supplier_order 5362 5362 0 0.00 5 48623 1276 0 0
srv_aggregate_perf_data 103 103 0 0.00 2766 400773 19918 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1532 129867 47543 0 0
srv_find_qd_qs_mism 1046695 1046695 0 0.00 0 16796 6 0 0
srv_make_invnt_saldo 565 565 0 0.00 20 74001 1143 0 0
srv_make_money_saldo 797 797 0 0.00 1 1715 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 134773 255800 201506 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4984 4984 0 0.00 0 13256 190 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 6117 139 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 5753 15 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 3683 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 1039 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 355 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 3138 25 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 33521 1812 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 2060 7 0 0
t$perf-norm:sp_client_order 20457 20457 0 0.00 0 4627 16 0 0
t$perf-norm:sp_customer_reserve 953 953 0 0.00 0 2183 8 0 0
t$perf-norm:sp_pay_from_customer 7093 7093 0 0.00 0 5456 9 0 0
t$perf-norm:sp_pay_to_supplier 5866 5866 0 0.00 0 6319 11 0 0
t$perf-norm:sp_reserve_write_off 9763 9763 0 0.00 0 9223 10 0 0
t$perf-norm:sp_supplier_invoice 5292 5292 0 0.00 0 4596 19 0 0
t$perf-norm:sp_supplier_order 5362 5362 0 0.00 0 4093 14 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 168 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 91 3 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 637 16 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 2240 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10230 9948 282 2.75 0 11939 19 0 282
v_all_customers 37340 37340 0 0.00 0 248 1 0 0
v_all_suppliers 29656 29656 0 0.00 0 27 1 0 0
v_all_wares 40904 40904 0 0.00 0 140 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 2410 11 0 0
v_cancel_client_order 3540 3540 0 0.00 0 1734 7 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2932 8 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2760 9 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1564 7 0 0
v_cancel_supplier_invoice 844 814 30 3.55 0 1221 15 0 30
v_cancel_supplier_order 776 776 0 0.00 0 4373 19 0 0
v_cancel_write_off 2096 2096 0 0.00 0 1782 13 0 0
v_max_id_clo_ord 5357 5357 0 0.00 0 511 1 0 0
v_max_id_clo_res 953 953 0 0.00 0 2557 13 0 0
v_max_id_ord_sup 5291 5291 0 0.00 0 3769 3 0 0
v_max_non_paid_invoice 1684 1684 0 0.00 0 4489 7 0 0
v_max_non_paid_realizn 3772 3772 0 0.00 0 2819 4 0 0
v_min_id_clo_ord 5357 5357 0 0.00 0 3124 8 0 0
v_min_id_clo_res 953 953 0 0.00 0 2181 4 0 0
v_min_id_ord_sup 5291 5291 0 0.00 0 4117 9 0 0
v_min_non_paid_invoice 5864 5864 0 0.00 0 7142 22 0 0
v_min_non_paid_realizn 7091 7091 0 0.00 0 6740 24 0 0
v_our_firm 10202 10202 0 0.00 0 18 1 0 0
v_reserve_write_off 19522 19522 0 0.00 0 5716 6 0 0
x_lock_depdocs_on_canc_invoice 11156 11156 0 0.00 0 14917 116 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 3720 309 0 0
x_make_qstorno_1000_1200 5362 5362 0 0.00 2 48593 1200 0 0
x_make_qstorno_1200_2000 5292 5292 0 0.00 3 90806 3526 0 0
x_make_qstorno_2100_3300 465105 465105 0 0.00 0 139959 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-11-2021 19:40:00 to 16-11-2021 19:40:04.

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:07:57 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:58 28788 8192000000 9905647424 10218631168 978838992 10.673 100 85 170 1 1007534848 5841968 22853888
17:10:36 22412 8192000000 9953400208 10275729408 1028842032 11.157 100 86 173 1 1058647760 7756752 22048976
17:12:04 14140 8192000000 9987134400 10323156992 1071736288 11.569 100 79 158 0 1101131872 9693616 19701968
17:13:29 16310 8192000000 10047983120 10399637504 1137213968 12.189 100 81 162 2 1166999344 10618224 19167152
17:14:49 10777 8192000000 10092700048 10458673152 1181700624 12.606 100 69 138 0 1208839408 11265536 15873248
17:16:03 5416 8192000000 10137867472 10513575936 1230010624 13.054 100 76 152 1 1258681552 11618192 17052736
17:17:12 3715 8192000000 10190541584 10571128832 1274907760 13.466 100 84 168 1 1307508480 13663744 18936976
17:18:18 1219 8192000000 10208652704 10593173504 1292006384 13.623 100 90 179 1 1326180784 14767536 19406864
17:19:20 4160 8192000000 10193778560 10595708928 1302727376 13.720 100 55 108 2 1325074112 10342464 12004272
17:20:25 6981 8192000000 10207034480 10606280704 1308284976 13.771 100 40 79 0 1324331296 6524384 9521936
17:21:33 6155 8192000000 10202460688 10617044992 1315360320 13.835 100 20 39 2 1324328560 5006608 3961632
17:22:39 47027 8192000000 10218009952 10633592832 1322410320 13.899 100 20 40 1 1332432352 4731520 5290512
17:24:27 10728 8192000000 10216101824 10637631488 1329839088 13.966 100 17 35 0 1338671040 5113536 3718416
17:25:38 9803 8192000000 10219686736 10644344832 1333803664 14.002 100 29 57 0 1347074944 5673216 7598064
17:26:49 7613 8192000000 10228554336 10652909568 1328332752 13.952 99 15 30 1 1337392368 5633376 3426240
17:27:57 66863 8192000000 10177283088 10598191104 1231182064 13.065 93 37 74 0 1245706560 5432768 9091728
17:30:06 7698 8192000000 10154193984 10559807488 1259766736 13.328 99 30 59 1 1273565440 6765696 7033008
17:31:16 10812 8192000000 10156227152 10557833216 1252300544 13.259 99 20 40 0 1262564432 5800128 4463760
17:32:28 11469 8192000000 10173000816 10565185536 1272241488 13.442 100 23 46 2 1283246272 5888352 5116432
17:33:40 19695 8192000000 10159578512 10567565312 1239158336 13.138 97 29 57 1 1251620096 5834720 6627040
17:35:01 7731 8192000000 10185828656 10601824256 1297084192 13.669 100 34 68 0 1311497344 5992016 8421136
17:36:10 14811 8192000000 10200741776 10615197696 1308519296 13.773 100 12 24 1 1316559152 5363696 2676160
17:37:25 13456 8192000000 10217132672 10636136448 1326104640 13.932 100 14 28 0 1334059520 5091408 2863472
17:38:43 60173 8192000000 10244476080 10661490688 1342556800 14.080 100 39 78 1 1360484624 9010624 8917200
17:40:48 6877 8192000000 10266144560 10685894656 1359721200 14.235 100 48 96 2 1383243664 12825776 10696688
17:42:00 18441 8192000000 10284288704 10704785408 1373229424 14.356 100 40 80 0 1398124448 15905184 8989840
17:43:20 12969 8192000000 10302870112 10722664448 1383659248 14.449 100 57 111 2 1414791552 18584656 12547648
17:44:37 6601 8192000000 10324959376 10743250944 1395408560 14.554 100 62 124 0 1431170384 21211520 14550304
17:45:48 10318 8192000000 10318065856 10739179520 1383672944 14.449 99 56 112 3 1421444128 24503328 13267856
17:47:06 8061 8192000000 10330951824 10753609728 1403966288 14.630 100 64 128 0 1439380096 20117840 15295968
17:48:18 5875 8192000000 10315894496 10740543488 1384143280 14.454 99 56 112 0 1417043120 18961648 13938192
17:49:32 1833 8192000000 10328923616 10748432384 1399739872 14.593 100 59 117 1 1435578592 20929056 14909664
17:50:44 13068 8192000000 10319355760 10737860608 1390011952 14.506 100 65 128 0 1429363936 23662640 15689344
17:52:06 4955 8192000000 10333299584 10751344640 1391869792 14.523 99 73 146 0 1437241808 27603968 17768048
17:53:15 1529 8192000000 10321900192 10724548608 1364528080 14.278 98 88 173 1 1414848368 28953824 21366464
17:54:23 9856 8192000000 10334769360 10752454656 1391703120 14.521 100 63 127 2 1442286400 34870272 15713008
17:55:40 4377 8192000000 10316567808 10730897408 1364753264 14.280 99 68 134 1 1421866832 39923776 17189792
17:56:48 2721 8192000000 10336714656 10745950208 1375238304 14.374 100 82 164 0 1438495520 42282560 20974656
17:57:53 24013 8192000000 10293953120 10709987328 1356552528 14.206 99 54 106 2 1397247488 26356624 14338336
17:59:23 4845 8192000000 10283746208 10702172160 1347144864 14.122 99 41 82 0 1385187264 27327568 10714832
18:00:35 14018 8192000000 10312503440 10735677440 1359451328 14.232 99 33 66 2 1405206688 35710672 10044688
18:01:55 16602 8192000000 10314113296 10741858304 1371517248 14.341 99 28 56 0 1412856864 33466400 7873216
18:03:15 12506 8192000000 10302055840 10740375552 1375803472 14.379 100 30 60 1 1417851808 33870032 8178304
18:04:58 44996 8192000000 10297353232 10734092288 1323396128 13.907 96 26 51 1 1363728176 33724048 6608000
18:06:59 24888 8192000000 10292743712 10723672064 1349045312 14.139 99 33 65 5 1377931280 21159728 7726240
18:08:35 67070 8192000000 10301810496 10743558144 1385440928 14.465 100 33 65 0 1404751680 11591472 7719280
18:10:49 39583 8192000000 10288698416 10731458560 1370232848 14.329 99 16 32 0 1390801984 16904368 3664768
18:12:29 66505 8192000000 10306612400 10761621504 1392448288 14.528 100 31 61 1 1424248272 24752032 7047952
18:14:37 78876 8192000000 10301382064 10765393920 1393871600 14.540 100 9 18 0 1416240736 19860128 2509008
18:16:56 88741 8192000000 10275318064 10725232640 1334847120 14.011 96 12 24 1 1354549920 16905888 2796912
18:20:00 32076 8192000000 10267257200 10731610112 1365436464 14.286 99 11 22 0 1376969600 9288624 2244512
18:21:50 67775 8192000000 10306170368 10751586304 1387940128 14.487 100 28 56 0 1411288928 16902480 6446320
18:24:19 21432 8192000000 10302627488 10753204224 1395509728 14.555 100 35 70 1 1412970784 9269504 8191552
18:25:40 44060 8192000000 10258888736 10719502336 1357042960 14.211 98 25 49 0 1371795216 8983808 5768448
18:27:30 59361 8192000000 10280384128 10739122176 1375949760 14.380 99 16 32 0 1387426048 7886944 3589344
18:29:30 88848 8192000000 10289124912 10768424960 1395898048 14.558 100 19 36 0 1415880432 15701040 4281344
18:32:05 74421 8192000000 10288786928 10751033344 1381525264 14.430 99 21 43 0 1402711360 16427600 4758496
18:34:20 73848 8192000000 10299362656 10752258048 1367541952 14.305 98 21 42 1 1385776864 13338128 4896784
18:36:42 41439 8192000000 10297599312 10754002944 1383303856 14.446 99 16 32 0 1400308784 13056352 3948576
18:38:24 54378 8192000000 10298849008 10756079616 1364404688 14.277 97 7 14 0 1377985600 12381248 1199664
18:40:29 79146 8192000000 10287700240 10750304256 1344984096 14.102 96 26 52 0 1365686768 15091504 5611168
18:42:48 50621 8192000000 10299194480 10754875392 1362931552 14.264 98 31 62 0 1388983536 18976432 7075552
18:44:41 78820 8192000000 10298180928 10740056064 1319628064 13.873 95 19 38 0 1332640336 8817008 4195264
18:47:11 69901 8192000000 10265716640 10708467712 1332271872 13.988 97 17 34 0 1342773552 6763680 3738000
18:49:33 44202 8192000000 10251801072 10680983552 1325959200 13.931 97 34 68 0 1341868544 8301728 7607616
18:51:18 66420 8192000000 10242886352 10697728000 1305639200 13.746 96 13 26 0 1318084784 9332368 3113216
18:53:25 70906 8192000000 10247171072 10699644928 1342888976 14.083 99 14 28 0 1354280848 8423392 2968480
18:55:37 81534 8192000000 10249275424 10705408000 1311121776 13.796 96 14 28 3 1322766640 8423744 3221120
18:58:11 43598 8192000000 10256757360 10705408000 1335361888 14.016 98 14 28 1 1351498240 13013104 3123248
18:59:54 62952 8192000000 10267384112 10712494080 1308767520 13.775 96 20 40 1 1330572016 17107728 4696768
19:02:12 82844 8192000000 10251338832 10698338304 1327504880 13.945 98 13 26 0 1345591536 15023728 3062928
19:04:36 22574 8192000000 10270360832 10697781248 1326084992 13.932 98 61 122 2 1350233840 9881472 14267376
19:06:15 124630 8192000000 10298037408 10757320704 1382118912 14.435 100 12 24 2 1402249968 17215408 2915648
19:09:22 100338 8192000000 10278457216 10728824832 1368867504 14.317 99 14 28 1 1381120864 9032336 3221024
19:12:04 129867 8192000000 10286133856 10747240448 1354040032 14.184 97 12 24 1 1370545792 13447792 3057968
19:15:31 56993 8192000000 10299775376 10765254656 1393081840 14.533 100 9 18 0 1409845120 14865328 1897952
19:17:28 77683 8192000000 10295003600 10743578624 1352563920 14.171 97 13 26 0 1365822752 10418208 2840624
19:19:46 34155 8192000000 10310008624 10759761920 1398024416 14.577 100 25 50 1 1413717776 9742256 5951104
19:21:27 80470 8192000000 10306350400 10767937536 1394761728 14.548 99 23 46 2 1415104480 15138288 5204464
19:23:51 55845 8192000000 10299133744 10746421248 1358252048 14.222 98 33 66 1 1377773440 11970192 7551200
19:25:57 29537 8192000000 10294188704 10744205312 1374152400 14.364 99 31 62 1 1394029584 13072480 6804704
19:27:33 54257 8192000000 10285864432 10733977600 1368669824 14.315 99 5 10 0 1381731872 12027184 1034864
19:29:28 119769 8192000000 10283305040 10739363840 1371613744 14.342 99 24 48 0 1391249440 14448496 5187200
19:32:43 66002 8192000000 10281310448 10740887552 1361436896 14.250 98 16 32 1 1375970432 10693456 3840080
19:35:03 74747 8192000000 10272655568 10721034240 1343533808 14.089 97 8 16 0 1354702912 9364416 1804688
19:37:36 29418 8192000000 9460300512 9807663104 572627856 6.533 42 6 11 0 584164272 10195696 1340720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-11-2021 19:40:06 to 16-11-2021 19:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 16-11-2021 19:40:22 to 16-11-2021 19:40:23.

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          234730
MON$OLDEST_ACTIVE               236445
MON$OLDEST_SNAPSHOT             236445
MON$NEXT_TRANSACTION            236445
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                       2019136
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
--- end of diff output ---

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

16.11.21 19:40:24. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-16 19:40:27 No severe PSQL-related problems occured
16.11.21 19:40:28. End of report.