Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.09.2022 01: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 15725868 kB
MemAvailable 30614608 kB
Buffers 0 kB
Cached 16023180 kB
SwapCached 2040 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.11
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-09-29 01:38:29
DTS_END                         2022-09-29 01:38:29.3080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54722
TRN_ID                          244415
ATT_ID                          707
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: 9009.07 26227 1081088
customer order: creation 171.03 361 20524
customer order: refuse 14.28 351 1714
order to supplier: creation 46.08 1264 5529
order to supplier: removal 3.26 94356 391
invoice (draft): creation 45.42 3441 5450
invoice (draft): removal 50.71 752 6085
invoice accept: apply 42.20 17528 5064
invoice accept: cancel 45.53 795 5463
customer reserve: creation 3884.98 174 466198
customer reserve: removal 4411.58 55 529389
realization accept: apply 84.70 194 10164
realization accept: cancel 80.52 102 9662
payment from customer: creation 58.65 105 7038
payment from customer: removal 6.16 90 739
payment to supplier: creation 48.63 135 5836
payment to supplier: removal 3.34 60 401
service: total inventory turnovers 4.81 1089 577
service: total monetary turnovers 6.25 28 750
service: aggregate perf. data 0.92 16659 110
service: refresh index statistics 0.03 387004 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-09-2022 01:39:54 to 29-09-2022 01:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2356
TEST_TIME 2 3329
TEST_TIME 3 5664
TEST_TIME 4 5539
TEST_TIME 5 6072
TEST_TIME 6 5882
TEST_TIME 7 5623
TEST_TIME 8 5550
TEST_TIME 9 5572
TEST_TIME 10 5763
TEST_TIME 11 5737
TEST_TIME 12 6074
TEST_TIME 13 6339
TEST_TIME 14 6671
TEST_TIME 15 6892
TEST_TIME 16 7452
TEST_TIME 17 7780
TEST_TIME 18 8388
TEST_TIME 19 8268
TEST_TIME 20 8723
TEST_TIME 21 9049
TEST_TIME 22 9141
TEST_TIME 23 9285
TEST_TIME 24 9450
TEST_TIME 25 9446
TEST_TIME 26 9741
TEST_TIME 27 10449
TEST_TIME 28 10401
TEST_TIME 29 10384
TEST_TIME 30 10340
TEST_TIME 31 10126
TEST_TIME 32 10126
TEST_TIME 33 10082
TEST_TIME 34 10010
TEST_TIME 35 9802
TEST_TIME 36 9731
TEST_TIME 37 9734
TEST_TIME 38 9829
TEST_TIME 39 9680
TEST_TIME 40 9575
TEST_TIME 41 9409
TEST_TIME 42 9364
TEST_TIME 43 9349
TEST_TIME 44 9218
TEST_TIME 45 9124
TEST_TIME 46 9094
TEST_TIME 47 8962
TEST_TIME 48 9011
TEST_TIME 49 9094
TEST_TIME 50 9171
TEST_TIME 51 9103
TEST_TIME 52 9201
TEST_TIME 53 9219
TEST_TIME 54 9219
TEST_TIME 55 9212
TEST_TIME 56 9214
TEST_TIME 57 9267
TEST_TIME 58 9337
TEST_TIME 59 9358
TEST_TIME 60 9360
TEST_TIME 61 9391
TEST_TIME 62 9377
TEST_TIME 63 9274
TEST_TIME 64 9191
TEST_TIME 65 9151
TEST_TIME 66 9140
TEST_TIME 67 9047
TEST_TIME 68 9144
TEST_TIME 69 9130
TEST_TIME 70 9107
TEST_TIME 71 9069
TEST_TIME 72 9035
TEST_TIME 73 9082
TEST_TIME 74 9089
TEST_TIME 75 9147
TEST_TIME 76 9173
TEST_TIME 77 9227
TEST_TIME 78 9269
TEST_TIME 79 9270
TEST_TIME 80 9266
TEST_TIME 81 9282
TEST_TIME 82 9328
TEST_TIME 83 9297
TEST_TIME 84 9349
TEST_TIME 85 9325
TEST_TIME 86 9244
TEST_TIME 87 9265
TEST_TIME 88 9234
TEST_TIME 89 9199
TEST_TIME 90 9180
TEST_TIME 91 9151
TEST_TIME 92 9094
TEST_TIME 93 9111
TEST_TIME 94 9098
TEST_TIME 95 9093
TEST_TIME 96 9069
TEST_TIME 97 9106
TEST_TIME 98 9125
TEST_TIME 99 9227
TEST_TIME 100 9204
TEST_TIME 101 9214
TEST_TIME 102 9235
TEST_TIME 103 9251
TEST_TIME 104 9227
TEST_TIME 105 9255
TEST_TIME 106 9364
TEST_TIME 107 9339
TEST_TIME 108 9321
TEST_TIME 109 9248
TEST_TIME 110 9229
TEST_TIME 111 9227
TEST_TIME 112 9216
TEST_TIME 113 9183
TEST_TIME 114 9162
TEST_TIME 115 9155
TEST_TIME 116 9107
TEST_TIME 117 9083
TEST_TIME 118 9066
TEST_TIME 119 9062
TEST_TIME 120 9017

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-09-2022 01:39:58 to 29-09-2022 01:40:02.

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 1511853 1511853 0 0.00 0 22187 6 0 0
doc_list_biud 1511852 1511852 0 0.00 0 30224 25 0 0
sp_add_invoice_to_stock 5183 5064 119 2.29 29 200547 17528 0 119
sp_cancel_adding_invoice 5463 5463 0 0.00 4 74812 796 0 0
sp_cancel_client_order 1714 1714 0 0.00 4 15511 351 0 0
sp_cancel_customer_reserve 529389 529389 0 0.00 0 27930 55 0 0
sp_cancel_pay_from_customer 739 739 0 0.00 2 11490 91 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 3731 61 0 0
sp_cancel_supplier_invoice 6091 6085 6 0.09 1 14205 753 0 6
sp_cancel_supplier_order 391 391 0 0.00 6 919438 94357 0 0
sp_cancel_write_off 9662 9662 0 0.00 1 14847 103 0 0
sp_client_order 20524 20524 0 0.00 3 22846 362 0 0
sp_customer_reserve 466198 466198 0 0.00 0 42062 174 0 0
sp_fill_shopping_cart 31496 31496 0 0.00 1 25045 47 0 0
sp_fill_shopping_cart_clo_res 466184 466184 0 0.00 0 25088 14 0 0
sp_get_clo_for_invoice 5047 5047 0 0.00 0 33476 324 0 0
sp_kill_cost_storno 16265 16265 0 0.00 0 7089 51 0 0
sp_kill_qstorno_ret_qs2qd 1074916 1074916 0 0.00 0 27926 30 0 0
sp_kill_qty_storno 567914 567914 0 0.00 0 30224 66 0 0
sp_lock_selected_doc 21026 21026 0 0.00 0 8784 6 0 0
sp_make_cost_storno 28084 28084 0 0.00 0 16328 55 0 0
sp_multiply_rows_for_pdistr 28084 28084 0 0.00 0 9680 25 0 0
sp_multiply_rows_for_qdistr 20524 20524 0 0.00 0 14231 154 0 0
sp_pay_from_customer 7038 7038 0 0.00 4 15281 106 0 0
sp_pay_to_supplier 5836 5836 0 0.00 4 15303 136 0 0
sp_payment_common 12874 12874 0 0.00 1 9987 27 0 0
sp_qd_handle_on_cancel_clo 1714 1714 0 0.00 0 15503 311 0 0
sp_qd_handle_on_invoice_upd_sts 10509 10509 0 0.00 0 30223 329 0 0
sp_qd_handle_on_reserve_upd_sts 10164 10164 0 0.00 0 18496 77 0 0
sp_reserve_write_off 10164 10164 0 0.00 6 18512 194 0 0
sp_supplier_invoice 5450 5450 0 0.00 6 115518 3441 0 0
sp_supplier_order 5529 5529 0 0.00 4 32508 1264 0 0
srv_aggregate_perf_data 110 110 0 0.00 2132 339997 16660 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1933 161479 43410 0 0
srv_find_qd_qs_mism 1032883 1032883 0 0.00 0 22186 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 56 33090 1089 0 0
srv_make_money_saldo 750 750 0 0.00 1 1014 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 206753 585116 387005 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5064 5064 0 0.00 0 10444 158 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 7169 112 0 0
t$perf-norm:sp_cancel_client_order 1714 1714 0 0.00 0 1111 7 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 895 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 739 739 0 0.00 0 2878 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 827 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 1821 17 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 16514 1408 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 10974 25 0 0
t$perf-norm:sp_client_order 20524 20524 0 0.00 0 5979 13 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 3148 11 0 0
t$perf-norm:sp_pay_from_customer 7038 7038 0 0.00 0 8241 11 0 0
t$perf-norm:sp_pay_to_supplier 5836 5836 0 0.00 0 6088 10 0 0
t$perf-norm:sp_reserve_write_off 10164 10164 0 0.00 0 6110 11 0 0
t$perf-norm:sp_supplier_invoice 5451 5451 0 0.00 0 7221 16 0 0
t$perf-norm:sp_supplier_order 5529 5529 0 0.00 0 6278 16 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 333 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 65 2 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1405 15 0 0
t$perf-norm:srv_make_money_saldo 750 750 0 0.00 0 2845 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10330 10092 238 2.30 0 6458 19 0 238
v_all_customers 36762 36762 0 0.00 0 33 1 0 0
v_all_suppliers 30230 30230 0 0.00 0 142 1 0 0
v_all_wares 41046 41046 0 0.00 0 44 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 3355 10 0 0
v_cancel_client_order 3428 3428 0 0.00 0 1193 4 0 0
v_cancel_customer_prepayment 1478 1478 0 0.00 0 5547 12 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 1922 4 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 3516 8 0 0
v_cancel_supplier_invoice 846 834 12 1.41 0 3507 19 0 12
v_cancel_supplier_order 782 782 0 0.00 0 5514 14 0 0
v_cancel_write_off 2002 2002 0 0.00 0 1361 12 0 0
v_max_id_clo_ord 5529 5529 0 0.00 0 2448 2 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 2479 10 0 0
v_max_id_ord_sup 5444 5444 0 0.00 0 11648 8 0 0
v_max_non_paid_invoice 1694 1694 0 0.00 0 4726 6 0 0
v_max_non_paid_realizn 4088 4088 0 0.00 0 3270 5 0 0
v_min_id_clo_ord 5529 5529 0 0.00 0 3894 10 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 11395 20 0 0
v_min_id_ord_sup 5444 5444 0 0.00 0 10136 13 0 0
v_min_non_paid_invoice 5836 5836 0 0.00 0 8390 20 0 0
v_min_non_paid_realizn 7038 7038 0 0.00 0 8390 17 0 0
v_our_firm 10184 10184 0 0.00 0 34 1 0 0
v_reserve_write_off 20328 20328 0 0.00 0 5383 6 0 0
x_lock_depdocs_on_canc_invoice 11131 11131 0 0.00 0 12512 110 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 22384 400 0 0
x_make_qstorno_1000_1200 5529 5529 0 0.00 1 32503 1174 0 0
x_make_qstorno_1200_2000 5450 5450 0 0.00 2 115287 3264 0 0
x_make_qstorno_2100_3300 466197 466197 0 0.00 0 42061 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-09-2022 01:40:03 to 29-09-2022 01:40:07.

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
23:08:33 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:34 27211 8192000000 9902567200 10214715392 978878848 10.673 100 89 178 0 1008093952 5879904 23335200
23:11:10 14173 8192000000 9948569472 10270564352 1037647392 11.242 100 88 176 0 1068955296 8503232 22804672
23:12:27 9542 8192000000 9960718176 10294018048 1056103632 11.419 100 82 164 0 1086586224 10215376 20267216
23:13:46 9245 8192000000 10024643456 10369466368 1111263744 11.944 100 82 164 0 1142026048 11410784 19351520
23:14:59 5848 8192000000 10075838608 10431700992 1159777216 12.401 100 79 158 0 1190772832 12976880 18018736
23:16:06 6476 8192000000 10118137184 10483015680 1202643952 12.801 100 69 134 0 1229982576 12039296 15299328
23:17:15 2278 8192000000 10151647808 10524073984 1239033568 13.137 100 86 170 1 1271618592 13758032 18826992
23:18:20 2015 8192000000 10181528464 10559934464 1265347728 13.379 100 79 153 1 1294046272 11894064 16804480
23:19:24 1582 8192000000 10206010128 10588835840 1294219648 13.643 100 90 175 0 1326289424 12223200 19846576
23:20:27 28077 8192000000 10198139344 10595418112 1298701088 13.683 100 57 113 2 1319745616 8456784 12587744
23:21:59 3610 8192000000 10213253328 10615869440 1310208464 13.788 100 57 114 1 1334622288 11006928 13406896
23:23:05 11908 8192000000 10201923024 10616721408 1316887984 13.849 100 25 50 0 1328511680 5630176 5993520
23:24:21 12561 8192000000 10225444640 10634362880 1322847328 13.902 100 32 63 0 1341287120 9834992 8604800
23:25:35 6538 8192000000 10210920544 10629324800 1322957632 13.903 100 33 63 0 1336693232 6885520 6850080
23:26:42 3950 8192000000 10226934672 10640728064 1330497856 13.972 100 27 54 1 1342241808 5504672 6239280
23:27:49 8149 8192000000 10210593488 10623823872 1311191136 13.797 99 25 44 1 1321298960 4678192 5429632
23:28:57 7998 8192000000 10160872896 10570330112 1275188352 13.469 99 20 41 0 1285679328 6107104 4383872
23:30:06 30575 8192000000 10163712368 10569187328 1207211696 12.843 93 28 56 0 1218336608 4840688 6284224
23:31:39 7775 8192000000 10152474768 10554785792 1241411776 13.159 98 18 36 0 1251278032 5953024 3913232
23:32:48 5621 8192000000 10143695152 10549776384 1260425952 13.334 100 26 51 0 1272583392 5678560 6478880
23:33:55 53976 8192000000 10187127520 10591432704 1289585264 13.600 100 44 87 0 1305624800 4967024 11072512
23:35:52 8869 8192000000 10186547248 10600697856 1296950640 13.668 100 29 57 1 1309879552 6928672 6000240
23:37:01 13371 8192000000 10189919984 10609971200 1307601120 13.764 100 9 18 1 1314824208 5184704 2038384
23:38:16 12290 8192000000 10227698416 10640265216 1328226128 13.951 100 24 48 1 1338984608 5435184 5323296
23:39:36 80598 8192000000 10244216656 10663452672 1344561856 14.099 100 33 66 0 1362599392 10850080 7187456
23:41:59 18835 8192000000 10267043776 10687741952 1359472928 14.233 100 40 78 1 1381799584 13035056 9291600
23:43:20 4795 8192000000 10292999200 10707939328 1375470704 14.376 100 58 116 0 1404065952 15388864 13206384
23:44:30 11411 8192000000 10292249008 10714374144 1382195920 14.436 100 38 76 1 1405898080 14377408 9324752
23:45:48 14581 8192000000 10282558656 10704986112 1360286032 14.240 98 40 80 0 1388911248 19307504 9317712
23:47:07 7065 8192000000 10315900928 10739650560 1400898624 14.603 100 51 98 0 1432732256 19731120 12102512
23:48:24 3100 8192000000 10321361184 10738253824 1395396448 14.554 100 60 120 0 1432729024 23312112 14020464
23:49:31 7296 8192000000 10330817168 10748125184 1388128176 14.489 99 56 112 1 1427408464 25379152 13901136
23:50:43 2006 8192000000 10313188048 10724651008 1376755344 14.388 100 70 135 1 1421049392 28414192 15879856
23:51:48 2639 8192000000 10297635760 10706272256 1360264928 14.240 100 62 124 1 1405612960 30233216 15114816
23:52:58 6460 8192000000 10314939008 10725019648 1375927600 14.380 100 74 148 0 1426600576 32140176 18532800
23:54:14 1932 8192000000 10328815920 10734727168 1376221056 14.383 100 77 153 0 1430983712 36159968 18602688
23:55:19 7505 8192000000 10298875968 10716323840 1360485536 14.242 99 41 80 0 1404489584 33100800 10903248
23:56:35 3837 8192000000 10303719968 10729033728 1346670800 14.118 98 48 92 2 1395692864 37060352 11961712
23:57:46 5332 8192000000 10309516048 10728558592 1367933472 14.309 100 52 103 0 1414141424 32636080 13571872
23:58:53 10016 8192000000 10325921136 10755366912 1389449824 14.501 100 38 76 1 1433653824 33897728 10306272
00:00:06 8590 8192000000 10292951840 10713956352 1340883200 14.065 98 34 68 0 1378849520 28857456 9108864
00:01:18 13365 8192000000 10289977408 10715901952 1363300720 14.267 100 28 56 0 1396174896 25479216 7394960
00:02:44 32196 8192000000 10306784624 10742489088 1376149680 14.382 100 20 38 0 1409532976 28410656 4972640
00:04:17 29456 8192000000 10284904608 10726096896 1340631344 14.063 99 17 34 0 1380646832 35348288 4667200
00:05:56 45840 8192000000 10280059760 10709946368 1354932784 14.192 100 34 67 2 1382177664 18832928 8411952
00:07:55 16624 8192000000 10289293200 10715602944 1363101952 14.265 100 52 102 0 1387492896 12948592 11442352
00:09:13 27521 8192000000 10266630704 10707607552 1346335696 14.114 98 32 63 1 1362732112 9649520 6746896
00:10:43 47816 8192000000 10277301504 10733543424 1360063904 14.238 99 19 39 0 1381263072 17125904 4073264
00:12:41 52503 8192000000 10299656384 10745040896 1384025584 14.453 100 16 32 0 1407999456 20098752 3875120
00:14:34 90149 8192000000 10287063168 10746441728 1383299088 14.446 100 17 34 0 1406134848 18726352 4109408
00:17:09 45344 8192000000 10300796480 10743353344 1392179040 14.525 100 36 71 0 1406579344 5540752 8859552
00:19:07 24756 8192000000 10309339376 10758541312 1403501184 14.626 100 44 87 1 1421831216 8802048 9527984
00:21:00 40337 8192000000 10289721184 10752348160 1395512464 14.555 100 14 28 0 1413099648 14413024 3174160
00:22:41 67916 8192000000 10312713472 10767765504 1410661120 14.690 100 13 24 0 1430410176 16845488 2903568
00:24:50 81205 8192000000 10318526976 10777853952 1409770592 14.682 100 27 50 1 1435713392 19045792 6897008
00:27:21 44560 8192000000 10312606224 10770817024 1415070432 14.729 100 13 27 1 1431283696 13447072 2766192
00:29:12 31977 8192000000 10310134608 10765578240 1387130176 14.480 98 20 40 0 1399133264 7243216 4759872
00:30:45 65026 8192000000 10307407344 10767409152 1398606400 14.583 99 12 24 0 1415111440 13943184 2561856
00:32:56 10646 8192000000 10287407440 10731462656 1381888752 14.433 99 55 109 1 1400007376 6010560 12108064
00:34:22 65682 8192000000 10295708192 10747998208 1340948528 14.066 96 35 70 0 1364573680 16001968 7623184
00:37:01 81816 8192000000 10271091072 10725613568 1315942704 13.840 95 13 26 1 1333778016 14426784 3408528
00:40:32 77462 8192000000 10253459440 10690125824 1329229600 13.960 97 18 36 1 1342029120 8742896 4056624
00:42:52 14789 8192000000 10262965840 10699927552 1346353984 14.115 99 36 70 1 1365674480 11551472 7769024
00:44:08 63917 8192000000 10237763984 10693742592 1305711008 13.747 96 12 24 0 1325350960 16703712 2936240
00:46:13 46401 8192000000 10251432160 10714198016 1321248032 13.888 98 17 34 0 1352315792 26822704 4245056
00:49:09 78303 8192000000 10264336640 10715848704 1311408720 13.799 96 19 38 0 1328492464 12538928 4544816
00:51:28 68703 8192000000 10249510240 10695168000 1292186000 13.624 95 17 34 0 1309969344 14129488 3653856
00:53:37 58388 8192000000 10279321392 10727333888 1351370624 14.160 99 18 36 0 1373932784 18234544 4327616
00:55:36 161479 8192000000 10263681872 10715979776 1316294512 13.843 96 9 18 0 1333989808 15646640 2048656
00:59:17 49874 8192000000 10269087680 10711347200 1327836288 13.948 98 13 26 0 1348578384 17418512 3323584
01:01:14 48013 8192000000 10280029248 10714951680 1347732832 14.127 99 25 50 0 1361325456 8397280 5195344
01:03:02 74692 8192000000 10265042656 10713546752 1357331152 14.213 99 32 64 1 1377196960 12578336 7287472
01:05:34 99191 8192000000 10294476816 10753986560 1384193888 14.454 100 21 42 0 1405696720 16912352 4590480
01:08:14 68451 8192000000 10283637840 10748485632 1381080096 14.426 99 7 14 0 1391810320 9010624 1719600
01:10:30 41998 8192000000 10292152128 10754293760 1401794944 14.611 100 9 18 0 1410649968 6644912 2210112
01:12:15 48403 8192000000 10309431680 10763403264 1392245584 14.526 99 15 29 1 1402392640 7323648 2823408
01:14:20 50044 8192000000 10324488576 10781777920 1399990096 14.595 98 22 44 0 1416955808 12100320 4865392
01:16:14 68099 8192000000 10310164176 10770882560 1384693808 14.458 98 17 34 0 1402795296 14246064 3855424
01:18:56 24668 8192000000 10311070352 10757578752 1379391936 14.411 98 42 85 0 1397817984 9450704 8975344
01:20:24 25364 8192000000 10314698080 10761363456 1391175360 14.516 99 44 84 0 1416192960 15200448 9817152
01:21:55 14074 8192000000 10316638720 10762330112 1388454608 14.492 99 30 60 0 1407489936 12762256 6273072
01:23:14 82503 8192000000 10315952960 10768551936 1371316496 14.339 98 36 72 1 1398329248 19221872 7790880
01:25:37 61515 8192000000 10275984976 10736570368 1352935792 14.174 98 19 39 1 1367604624 10768576 3900256
01:27:38 64105 8192000000 10288953296 10739818496 1347518448 14.125 97 21 41 0 1359481504 7689616 4273440
01:30:07 71356 8192000000 10267088224 10732417024 1353385872 14.178 98 8 16 0 1368622464 13604624 1631968
01:32:22 58118 8192000000 10279160528 10735046656 1364504304 14.278 98 20 42 0 1376505376 7645024 4356048
01:34:20 70073 8192000000 10263815008 10719932416 1325522400 13.927 96 18 36 0 1337191968 7388432 4281136
01:36:37 77454 8192000000 10271970848 10725294080 1338674240 14.045 97 9 18 0 1349622400 9033424 1914736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-09-2022 01:40:09 to 29-09-2022 01:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-09-2022 01:40:25 to 29-09-2022 01:40:27.

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          242872
MON$OLDEST_ACTIVE               244897
MON$OLDEST_SNAPSHOT             244897
MON$NEXT_TRANSACTION            244897
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                       2018944
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:209: 
:210: mscs-test-161.budzdorov.ru    Thu Sep 29 01:38:31 2022
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Thu Sep 29 01:38:39 2022
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Thu Sep 29 01:38:40 2022
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
:221: 
:222: mscs-test-161.budzdorov.ru    Thu Sep 29 01:38:42 2022
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:224: 
--- end of diff output ---

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

29.09.22 01:40:28. 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-09-29 01:40:30 No severe PSQL-related problems occured
29.09.22 01:40:32. End of report.