Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.12.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 16729824 kB
MemAvailable 31463096 kB
Buffers 0 kB
Cached 14965856 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.9
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-12-14 19:38:00
DTS_END                         2021-12-14 19:38:00.6770
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/50188
TRN_ID                          240233
ATT_ID                          649
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: 8999.49 27628 1079939
customer order: creation 166.73 392 20007
customer order: refuse 14.16 398 1699
order to supplier: creation 43.36 1460 5203
order to supplier: removal 3.26 112557 391
invoice (draft): creation 44.25 3954 5310
invoice (draft): removal 50.28 896 6033
invoice accept: apply 40.95 18919 4914
invoice accept: cancel 45.47 888 5456
customer reserve: creation 3837.82 183 460538
customer reserve: removal 4472.98 64 536758
realization accept: apply 80.34 199 9641
realization accept: cancel 74.10 117 8892
payment from customer: creation 57.26 120 6871
payment from customer: removal 5.47 86 656
payment to supplier: creation 47.13 138 5656
payment to supplier: removal 3.27 98 392
service: total inventory turnovers 5.03 1175 603
service: total monetary turnovers 6.79 28 815
service: aggregate perf. data 0.83 20785 100
service: refresh index statistics 0.03 390109 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-12-2021 19:39:39 to 14-12-2021 19:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3622
TEST_TIME 2 3912
TEST_TIME 3 3945
TEST_TIME 4 3565
TEST_TIME 5 3917
TEST_TIME 6 4806
TEST_TIME 7 4731
TEST_TIME 8 4931
TEST_TIME 9 5733
TEST_TIME 10 6647
TEST_TIME 11 7104
TEST_TIME 12 7457
TEST_TIME 13 7638
TEST_TIME 14 7788
TEST_TIME 15 7798
TEST_TIME 16 8011
TEST_TIME 17 8162
TEST_TIME 18 8154
TEST_TIME 19 8203
TEST_TIME 20 8227
TEST_TIME 21 8569
TEST_TIME 22 8998
TEST_TIME 23 9195
TEST_TIME 24 9351
TEST_TIME 25 9904
TEST_TIME 26 10169
TEST_TIME 27 10190
TEST_TIME 28 10766
TEST_TIME 29 11206
TEST_TIME 30 11048
TEST_TIME 31 10828
TEST_TIME 32 10580
TEST_TIME 33 10379
TEST_TIME 34 10391
TEST_TIME 35 10170
TEST_TIME 36 10126
TEST_TIME 37 10223
TEST_TIME 38 10046
TEST_TIME 39 9873
TEST_TIME 40 9712
TEST_TIME 41 9732
TEST_TIME 42 9718
TEST_TIME 43 9597
TEST_TIME 44 9477
TEST_TIME 45 9477
TEST_TIME 46 9537
TEST_TIME 47 9404
TEST_TIME 48 9292
TEST_TIME 49 9165
TEST_TIME 50 9173
TEST_TIME 51 9208
TEST_TIME 52 9173
TEST_TIME 53 9214
TEST_TIME 54 9179
TEST_TIME 55 9317
TEST_TIME 56 9289
TEST_TIME 57 9334
TEST_TIME 58 9331
TEST_TIME 59 9266
TEST_TIME 60 9352
TEST_TIME 61 9413
TEST_TIME 62 9436
TEST_TIME 63 9500
TEST_TIME 64 9476
TEST_TIME 65 9358
TEST_TIME 66 9284
TEST_TIME 67 9245
TEST_TIME 68 9181
TEST_TIME 69 9177
TEST_TIME 70 9146
TEST_TIME 71 9160
TEST_TIME 72 9069
TEST_TIME 73 9253
TEST_TIME 74 9248
TEST_TIME 75 9236
TEST_TIME 76 9301
TEST_TIME 77 9293
TEST_TIME 78 9247
TEST_TIME 79 9295
TEST_TIME 80 9269
TEST_TIME 81 9367
TEST_TIME 82 9338
TEST_TIME 83 9435
TEST_TIME 84 9371
TEST_TIME 85 9288
TEST_TIME 86 9298
TEST_TIME 87 9255
TEST_TIME 88 9235
TEST_TIME 89 9230
TEST_TIME 90 9227
TEST_TIME 91 9193
TEST_TIME 92 9151
TEST_TIME 93 9105
TEST_TIME 94 9118
TEST_TIME 95 9119
TEST_TIME 96 9120
TEST_TIME 97 9091
TEST_TIME 98 9148
TEST_TIME 99 9205
TEST_TIME 100 9220
TEST_TIME 101 9173
TEST_TIME 102 9265
TEST_TIME 103 9235
TEST_TIME 104 9282
TEST_TIME 105 9263
TEST_TIME 106 9330
TEST_TIME 107 9312
TEST_TIME 108 9328
TEST_TIME 109 9264
TEST_TIME 110 9200
TEST_TIME 111 9173
TEST_TIME 112 9195
TEST_TIME 113 9166
TEST_TIME 114 9135
TEST_TIME 115 9141
TEST_TIME 116 9127
TEST_TIME 117 9105
TEST_TIME 118 9073
TEST_TIME 119 9052
TEST_TIME 120 9001

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-12-2021 19:39:43 to 14-12-2021 19:39:46.

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 1505845 1505845 0 0.00 0 20882 6 0 0
doc_list_biud 1505843 1505843 0 0.00 0 33833 29 0 0
sp_add_invoice_to_stock 5022 4914 108 2.15 29 173779 18920 0 108
sp_cancel_adding_invoice 5456 5456 0 0.00 5 69816 889 0 0
sp_cancel_client_order 1699 1699 0 0.00 5 33839 399 0 0
sp_cancel_customer_reserve 536758 536758 0 0.00 1 18459 64 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 7386 86 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 2 5501 98 0 0
sp_cancel_supplier_invoice 6037 6033 4 0.06 2 14646 896 0 4
sp_cancel_supplier_order 391 391 0 0.00 5 928221 112558 0 0
sp_cancel_write_off 8892 8892 0 0.00 1 18745 118 0 0
sp_client_order 20007 20007 0 0.00 4 28523 393 0 0
sp_customer_reserve 460538 460538 0 0.00 0 79175 184 0 0
sp_fill_shopping_cart 30520 30520 0 0.00 1 33787 46 0 0
sp_fill_shopping_cart_clo_res 460534 460534 0 0.00 0 12041 14 0 0
sp_get_clo_for_invoice 4910 4910 0 0.00 0 24595 287 0 0
sp_kill_cost_storno 15396 15396 0 0.00 0 18743 65 0 0
sp_kill_qstorno_ret_qs2qd 1088832 1088832 0 0.00 0 18892 35 0 0
sp_kill_qty_storno 573780 573780 0 0.00 0 33833 76 0 0
sp_lock_selected_doc 20220 20220 0 0.00 0 7802 4 0 0
sp_make_cost_storno 27078 27078 0 0.00 0 13349 58 0 0
sp_multiply_rows_for_pdistr 27078 27078 0 0.00 0 8163 33 0 0
sp_multiply_rows_for_qdistr 20007 20007 0 0.00 0 9303 153 0 0
sp_pay_from_customer 6871 6871 0 0.00 4 12462 121 0 0
sp_pay_to_supplier 5656 5656 0 0.00 5 13921 138 0 0
sp_payment_common 12527 12527 0 0.00 2 8356 28 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 33833 364 0 0
sp_qd_handle_on_invoice_upd_sts 10366 10366 0 0.00 0 28578 370 0 0
sp_qd_handle_on_reserve_upd_sts 9641 9641 0 0.00 0 19954 72 0 0
sp_reserve_write_off 9641 9641 0 0.00 6 20005 199 0 0
sp_supplier_invoice 5310 5310 0 0.00 8 100566 3954 0 0
sp_supplier_order 5203 5203 0 0.00 5 31126 1460 0 0
srv_aggregate_perf_data 100 100 0 0.00 2529 402946 20786 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1985 149452 46113 0 0
srv_find_qd_qs_mism 1033026 1033026 0 0.00 0 20881 5 0 0
srv_make_invnt_saldo 603 603 0 0.00 38 89228 1175 0 0
srv_make_money_saldo 815 815 0 0.00 0 4431 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 321443 466524 390110 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4914 4914 0 0.00 0 12276 208 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 5125 130 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 1130 11 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 1372 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 4956 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 390 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 4218 28 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 25357 1525 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 9124 16 0 0
t$perf-norm:sp_client_order 20007 20007 0 0.00 0 15133 21 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 5731 17 0 0
t$perf-norm:sp_pay_from_customer 6871 6871 0 0.00 0 6937 12 0 0
t$perf-norm:sp_pay_to_supplier 5656 5656 0 0.00 0 5371 13 0 0
t$perf-norm:sp_reserve_write_off 9641 9641 0 0.00 0 15976 13 0 0
t$perf-norm:sp_supplier_invoice 5310 5310 0 0.00 0 20256 20 0 0
t$perf-norm:sp_supplier_order 5203 5203 0 0.00 0 14992 22 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 270 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 162 3 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 1551 24 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 1732 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 10036 9820 216 2.15 0 5619 21 0 216
v_all_customers 36062 36062 0 0.00 0 20 1 0 0
v_all_suppliers 29110 29110 0 0.00 0 21 1 0 0
v_all_wares 40014 40014 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1150 8 0 0
v_cancel_client_order 3398 3398 0 0.00 0 2599 7 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 7210 13 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 1256 5 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1879 6 0 0
v_cancel_supplier_invoice 832 824 8 0.96 0 815 14 0 8
v_cancel_supplier_order 782 782 0 0.00 0 3432 15 0 0
v_cancel_write_off 2084 2084 0 0.00 0 2292 16 0 0
v_max_id_clo_ord 5203 5203 0 0.00 0 3506 2 0 0
v_max_id_clo_res 973 973 0 0.00 0 1646 13 0 0
v_max_id_ord_sup 5310 5310 0 0.00 0 3190 6 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 3263 5 0 0
v_max_non_paid_realizn 3842 3842 0 0.00 0 3263 5 0 0
v_min_id_clo_ord 5203 5203 0 0.00 0 795 4 0 0
v_min_id_clo_res 973 973 0 0.00 0 5594 20 0 0
v_min_id_ord_sup 5310 5310 0 0.00 0 7074 6 0 0
v_min_non_paid_invoice 5656 5656 0 0.00 0 7624 20 0 0
v_min_non_paid_realizn 6871 6871 0 0.00 0 7237 18 0 0
v_our_firm 10010 10010 0 0.00 0 19 1 0 0
v_reserve_write_off 19282 19282 0 0.00 0 3426 6 0 0
x_lock_depdocs_on_canc_invoice 11077 11077 0 0.00 0 30043 144 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 3780 368 0 0
x_make_qstorno_1000_1200 5203 5203 0 0.00 1 31109 1376 0 0
x_make_qstorno_1200_2000 5310 5310 0 0.00 4 100545 3776 0 0
x_make_qstorno_2100_3300 460538 460538 0 0.00 0 79151 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-12-2021 19:39:47 to 14-12-2021 19:39:51.

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:04 8 8192000000 8801945216 8814235648 7669360 0.093 3 1 2 0 7770320 58176 42784
17:09:04 17057 8192000000 9875878272 10183241728 962215056 10.511 100 90 180 0 991580128 5272272 24092800
17:10:30 26328 8192000000 9926048672 10243129344 1001983248 10.898 100 86 172 0 1032664928 7701344 22980336
17:12:04 18409 8192000000 9967738640 10296295424 1044289904 11.306 100 89 179 0 1075774240 8636688 22847648
17:13:30 12428 8192000000 10034743936 10382872576 1119451568 12.022 100 81 162 0 1149245408 10589360 19204480
17:14:49 5622 8192000000 10093193488 10453762048 1178090240 12.572 100 81 160 1 1208878352 12155600 18632512
17:15:59 6503 8192000000 10137139776 10505674752 1219025616 12.953 100 86 171 0 1250990256 13307408 18657232
17:17:07 6428 8192000000 10162435328 10544173056 1255192688 13.286 100 76 152 1 1284950448 14054752 15703008
17:18:14 1425 8192000000 10204295168 10589757440 1295483840 13.654 100 92 184 2 1327439040 11660000 20295200
17:19:16 3693 8192000000 10192030960 10602921984 1307525328 13.764 100 32 64 2 1326652928 12283936 6843664
17:20:24 54875 8192000000 10205388160 10621374464 1317694992 13.856 100 37 74 2 1332666208 6284800 8686416
17:22:20 3854 8192000000 10211334128 10630901760 1322812624 13.902 100 28 56 1 1335798592 6569232 6416736
17:23:26 7559 8192000000 10218867200 10641182720 1331357216 13.979 100 19 38 2 1342222112 6510544 4354352
17:24:36 59865 8192000000 10239450320 10653605888 1342575968 14.081 100 33 66 2 1354992784 4991472 7425344
17:26:36 4997 8192000000 10227017696 10649141248 1335078352 14.013 99 35 70 2 1350372656 7433344 7860960
17:27:44 6485 8192000000 10209134752 10627612672 1324215168 13.915 100 25 50 0 1335987632 5914336 5858128
17:28:52 15961 8192000000 10142822784 10529828864 1171359248 12.510 91 27 54 0 1183827824 4910288 7558288
17:30:10 16991 8192000000 10115917424 10518769664 1156717872 12.373 92 24 48 1 1167351760 5746096 4887792
17:31:27 6810 8192000000 10143918672 10539442176 1246751520 13.208 100 30 60 0 1260042080 6056032 7234528
17:32:36 6074 8192000000 10155081504 10558795776 1252938224 13.265 99 26 52 1 1264566576 5247168 6381184
17:33:43 54420 8192000000 10188201168 10588663808 1282354880 13.535 100 48 96 0 1303243824 9785744 11103200
17:35:39 4233 8192000000 10186030768 10594643968 1293609248 13.637 100 30 61 1 1307097664 6758960 6729456
17:36:44 4308 8192000000 10197588192 10608508928 1302658944 13.719 100 35 68 3 1317477312 6306880 8511488
17:37:48 13559 8192000000 10196278032 10613465088 1307742304 13.766 100 22 44 2 1318236992 5645424 4849264
17:39:13 7737 8192000000 10259233440 10658807808 1340190080 14.059 100 64 127 0 1365660016 11024640 14445296
17:40:24 78041 8192000000 10255485312 10676617216 1352380016 14.169 100 27 54 1 1373078144 14813232 5884896
17:42:45 23377 8192000000 10267174704 10691641344 1361719632 14.253 100 27 54 1 1387934976 19378768 6836576
17:44:11 22109 8192000000 10292343808 10712268800 1373758736 14.361 100 50 100 0 1406474624 20775072 11940816
17:45:58 10854 8192000000 10307108512 10723627008 1384057200 14.453 100 55 108 1 1417798880 20896144 12845536
17:47:13 2399 8192000000 10322537792 10737983488 1378975040 14.407 99 67 130 3 1420402208 26068336 15358832
17:48:20 11610 8192000000 10326026688 10744463360 1390547680 14.511 100 66 131 5 1433820112 27761984 15510448
17:49:39 2024 8192000000 10326819232 10734473216 1384404832 14.456 100 85 169 2 1429028112 24812128 19811152
17:50:43 5063 8192000000 10314915936 10723577856 1369382224 14.322 99 74 146 1 1416484912 28883536 18219152
17:51:56 8786 8192000000 10312711600 10728947712 1375623648 14.377 100 54 109 1 1420293520 31505232 13164640
17:53:17 6108 8192000000 10304446064 10709823488 1359700944 14.235 100 65 130 0 1412238448 36135056 16402448
17:54:29 4187 8192000000 10337799120 10743169024 1380309344 14.419 100 76 152 1 1441443136 42165888 18967904
17:55:36 4925 8192000000 10346678608 10758725632 1392608304 14.529 100 60 120 0 1451065408 42863536 15593568
17:56:47 3198 8192000000 10374295024 10780180480 1407633824 14.663 100 75 146 2 1471425488 45390096 18401568
17:57:55 6942 8192000000 10331689632 10738348032 1349649104 14.144 98 67 134 1 1420036032 51817840 18569088
17:59:08 3325 8192000000 10354007456 10767675392 1382715840 14.441 100 64 124 1 1462896976 63088272 17092864
18:00:16 9066 8192000000 10352105792 10771603456 1384299088 14.455 100 56 112 2 1456992800 57255200 15438512
18:01:33 1982 8192000000 10359597872 10776879104 1390384480 14.509 100 60 116 0 1457128928 51874480 14869968
18:02:40 7020 8192000000 10351148304 10782793728 1387762144 14.486 100 35 70 0 1459726816 61857712 10106960
18:04:01 18881 8192000000 10332338736 10777272320 1379266912 14.410 100 23 46 2 1440959440 55262816 6429712
18:05:36 21029 8192000000 10338313968 10779811840 1373447088 14.358 100 17 34 3 1434453376 55882336 5123952
18:06:58 19391 8192000000 10305394048 10747772928 1359949664 14.237 99 37 74 2 1402204704 33613376 8641664
18:08:28 37417 8192000000 10287270592 10737733632 1393892960 14.541 100 21 41 1 1404626704 6440208 4293536
18:10:06 31719 8192000000 10294336640 10741739520 1391259984 14.517 100 31 62 0 1406466800 7790576 7416240
18:11:40 54312 8192000000 10297594848 10760269824 1387937360 14.487 99 9 19 1 1399901872 9928688 2035824
18:14:27 137589 8192000000 10295612640 10745802752 1360934640 14.246 97 18 36 1 1373304384 8407008 3962736
18:17:49 52127 8192000000 10311225744 10759753728 1400059968 14.596 100 32 62 1 1421747216 14626624 7060624
18:19:45 7226 8192000000 10326600640 10767241216 1405110672 14.640 100 69 133 1 1428177504 7766608 15300224
18:20:57 93921 8192000000 10309364048 10758733824 1389979184 14.506 99 29 57 0 1403767776 6887056 6901536
18:23:33 43327 8192000000 10299934992 10767572992 1409991712 14.684 100 30 60 1 1429716544 12817344 6907488
18:25:27 69811 8192000000 10303918496 10762649600 1396777232 14.566 99 19 38 1 1412119616 10335344 5007040
18:27:42 69359 8192000000 10297326144 10772058112 1402096368 14.614 100 18 36 0 1419297472 13186400 4014704
18:29:52 50101 8192000000 10296239248 10758037504 1364652480 14.279 97 12 24 1 1376343632 9300256 2390896
18:31:43 31309 8192000000 10293630320 10747215872 1361077136 14.247 98 20 38 1 1379738080 13392496 5268448
18:33:14 118519 8192000000 10287096528 10743734272 1354485568 14.188 98 10 20 0 1372591440 16022240 2083632
18:36:13 96240 8192000000 10293197888 10743857152 1333300656 13.997 96 14 28 0 1348339328 11942496 3096176
18:39:13 91399 8192000000 10265406224 10719113216 1272512112 13.445 93 11 22 0 1289242064 14423824 2306128
18:41:51 113173 8192000000 10280705808 10722607104 1335193136 14.014 97 21 39 1 1351150560 11617712 4339712
18:45:30 66920 8192000000 10235913744 10685227008 1289058032 13.596 95 7 14 0 1299547248 8664608 1824608
18:47:42 79590 8192000000 10261858528 10722795520 1333747456 14.001 98 11 22 0 1353164800 16693792 2723552
18:50:07 28088 8192000000 10270349696 10712469504 1347244416 14.123 99 44 88 3 1370055040 12592064 10218560
18:51:54 57321 8192000000 10232356512 10679648256 1280479568 13.517 95 11 22 1 1296641216 13896224 2265424
18:53:53 54420 8192000000 10255316336 10711543808 1337054640 14.031 100 7 14 0 1354906752 16466320 1385792
18:55:49 33163 8192000000 10280899136 10717224960 1345652592 14.108 99 23 46 1 1358561648 7955920 4953136
18:57:29 74290 8192000000 10268041920 10713997312 1332333376 13.988 98 31 62 2 1350639360 11176400 7129584
18:59:43 83147 8192000000 10293504528 10735194112 1345587616 14.108 99 18 36 0 1360781696 10934176 4259904
19:02:07 149452 8192000000 10278610192 10732277760 1340607616 14.063 98 15 30 0 1359098544 15129984 3360944
19:05:39 11929 8192000000 10270673872 10728693760 1357118128 14.211 99 17 34 0 1374072784 13029376 3925280
19:06:54 58188 8192000000 10269965696 10738319360 1375064816 14.372 100 7 14 1 1387494048 10927088 1502144
19:08:53 52547 8192000000 10284614448 10746949632 1378631728 14.404 99 19 37 0 1389594576 7414576 3548272
19:10:46 65916 8192000000 10292441248 10753167360 1393292512 14.535 100 14 26 1 1402077248 6242704 2542032
19:12:59 69191 8192000000 10309622096 10776428544 1402893168 14.621 100 13 26 0 1416009856 10254640 2862048
19:15:16 44724 8192000000 10324319376 10781888512 1398310432 14.580 99 35 70 2 1425582160 18378128 8893600
19:17:07 111246 8192000000 10321602688 10772312064 1354481488 14.188 96 13 26 1 1374367872 16870320 3016064
19:20:01 23452 8192000000 10316670352 10771181568 1373611104 14.359 98 42 82 0 1403919008 20743648 9564256
19:21:31 43358 8192000000 10318312288 10768220160 1381655248 14.431 99 45 91 1 1404631088 13060080 9915760
19:23:15 93074 8192000000 10307491856 10764935168 1366648976 14.297 98 14 28 0 1387373712 17915904 2808832
19:26:02 79273 8192000000 10287530784 10742763520 1334691456 14.010 96 28 56 1 1351312784 10195664 6425664
19:28:24 95077 8192000000 10266880496 10723491840 1324669488 13.919 96 22 44 0 1342594704 12504752 5420464
19:31:07 32186 8192000000 10276949232 10732216320 1362996656 14.264 99 24 48 0 1380966960 12052912 5917392
19:32:40 65129 8192000000 10275697760 10738466816 1343362384 14.088 97 16 30 1 1357311360 10765472 3183504
19:35:36 73196 8192000000 10260482976 10714972160 1341618240 14.072 98 14 28 1 1352040176 7392000 3029936
19:37:51 17096 8192000000 9026745792 9243316224 198176256 2.362 15 2 4 0 201777088 3247072 353760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-12-2021 19:39:53 to 14-12-2021 19:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2021 19:40:09 to 14-12-2021 19:40:11.

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          240444
MON$OLDEST_ACTIVE               240727
MON$OLDEST_SNAPSHOT             240727
MON$NEXT_TRANSACTION            240727
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                       2027680
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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 ---
:183: 
:184: mscs-test-161.budzdorov.ru    Tue Dec 14 19:38:19 2021
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Tue Dec 14 19:38:20 2021
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:190: 
--- end of diff output ---

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

14.12.21 19:40:12. 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-12-14 19:40:14 No severe PSQL-related problems occured
14.12.21 19:40:15. End of report.