Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.06.2022 04: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 16141920 kB
MemAvailable 31205976 kB
Buffers 20 kB
Cached 15539860 kB
SwapCached 2508 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-06-25 04:37:18
DTS_END                         2022-06-25 04:37:18.7880
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(68:17)
IP                              ::1/59196
TRN_ID                          213503
ATT_ID                          659
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: 8938.12 28738 1072574
customer order: creation 159.25 456 19110
customer order: refuse 13.57 377 1628
order to supplier: creation 42.42 1282 5090
order to supplier: removal 3.48 153570 417
invoice (draft): creation 42.79 2874 5135
invoice (draft): removal 50.29 929 6035
invoice accept: apply 39.13 27922 4696
invoice accept: cancel 45.57 1082 5468
customer reserve: creation 3752.60 261 450312
customer reserve: removal 4511.14 93 541337
realization accept: apply 77.79 272 9335
realization accept: cancel 77.28 170 9273
payment from customer: creation 56.70 184 6804
payment from customer: removal 5.49 120 659
payment to supplier: creation 45.97 194 5516
payment to supplier: removal 3.28 179 393
service: total inventory turnovers 3.51 4203 421
service: total monetary turnovers 6.95 396 834
service: aggregate perf. data 0.89 16894 107
service: refresh index statistics 0.03 363305 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-06-2022 04:39:24 to 25-06-2022 04:39:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7504
TEST_TIME 2 5285
TEST_TIME 3 5369
TEST_TIME 4 4993
TEST_TIME 5 5278
TEST_TIME 6 5568
TEST_TIME 7 5488
TEST_TIME 8 5852
TEST_TIME 9 5829
TEST_TIME 10 5880
TEST_TIME 11 5957
TEST_TIME 12 6409
TEST_TIME 13 6636
TEST_TIME 14 6767
TEST_TIME 15 6696
TEST_TIME 16 6754
TEST_TIME 17 7059
TEST_TIME 18 7309
TEST_TIME 19 7504
TEST_TIME 20 7444
TEST_TIME 21 7623
TEST_TIME 22 7853
TEST_TIME 23 8238
TEST_TIME 24 8064
TEST_TIME 25 8531
TEST_TIME 26 9351
TEST_TIME 27 9564
TEST_TIME 28 10075
TEST_TIME 29 10347
TEST_TIME 30 10542
TEST_TIME 31 10583
TEST_TIME 32 10883
TEST_TIME 33 10858
TEST_TIME 34 10715
TEST_TIME 35 10734
TEST_TIME 36 10616
TEST_TIME 37 10627
TEST_TIME 38 10519
TEST_TIME 39 10468
TEST_TIME 40 10579
TEST_TIME 41 10569
TEST_TIME 42 10399
TEST_TIME 43 10186
TEST_TIME 44 10011
TEST_TIME 45 9920
TEST_TIME 46 9887
TEST_TIME 47 9892
TEST_TIME 48 9757
TEST_TIME 49 9690
TEST_TIME 50 9650
TEST_TIME 51 9548
TEST_TIME 52 9455
TEST_TIME 53 9446
TEST_TIME 54 9655
TEST_TIME 55 9632
TEST_TIME 56 9747
TEST_TIME 57 9830
TEST_TIME 58 9735
TEST_TIME 59 9707
TEST_TIME 60 9649
TEST_TIME 61 9683
TEST_TIME 62 9564
TEST_TIME 63 9605
TEST_TIME 64 9620
TEST_TIME 65 9596
TEST_TIME 66 9576
TEST_TIME 67 9624
TEST_TIME 68 9534
TEST_TIME 69 9473
TEST_TIME 70 9451
TEST_TIME 71 9411
TEST_TIME 72 9374
TEST_TIME 73 9280
TEST_TIME 74 9281
TEST_TIME 75 9321
TEST_TIME 76 9310
TEST_TIME 77 9289
TEST_TIME 78 9255
TEST_TIME 79 9265
TEST_TIME 80 9249
TEST_TIME 81 9228
TEST_TIME 82 9214
TEST_TIME 83 9350
TEST_TIME 84 9342
TEST_TIME 85 9318
TEST_TIME 86 9390
TEST_TIME 87 9357
TEST_TIME 88 9435
TEST_TIME 89 9370
TEST_TIME 90 9290
TEST_TIME 91 9266
TEST_TIME 92 9252
TEST_TIME 93 9190
TEST_TIME 94 9185
TEST_TIME 95 9145
TEST_TIME 96 9097
TEST_TIME 97 9080
TEST_TIME 98 9099
TEST_TIME 99 9049
TEST_TIME 100 9041
TEST_TIME 101 9019
TEST_TIME 102 9055
TEST_TIME 103 9118
TEST_TIME 104 9135
TEST_TIME 105 9136
TEST_TIME 106 9144
TEST_TIME 107 9167
TEST_TIME 108 9175
TEST_TIME 109 9148
TEST_TIME 110 9203
TEST_TIME 111 9264
TEST_TIME 112 9222
TEST_TIME 113 9146
TEST_TIME 114 9132
TEST_TIME 115 9117
TEST_TIME 116 9099
TEST_TIME 117 9050
TEST_TIME 118 9042
TEST_TIME 119 9030
TEST_TIME 120 8961

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-06-2022 04:39:28 to 25-06-2022 04:39:31.

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 1485424 1485424 0 0.00 0 36271 14 0 0
doc_list_biud 1485418 1485418 0 0.00 0 33289 39 0 0
sp_add_invoice_to_stock 4834 4696 138 2.85 44 259602 27923 0 138
sp_cancel_adding_invoice 5468 5468 0 0.00 6 75754 1083 0 0
sp_cancel_client_order 1628 1628 0 0.00 6 13940 378 0 0
sp_cancel_customer_reserve 541337 541337 0 0.00 1 33290 93 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 12217 121 0 0
sp_cancel_pay_to_supplier 393 393 0 0.00 3 10647 179 0 0
sp_cancel_supplier_invoice 6051 6035 16 0.26 3 21048 930 0 16
sp_cancel_supplier_order 417 417 0 0.00 10 1335200 153570 0 0
sp_cancel_write_off 9273 9273 0 0.00 2 23462 170 0 0
sp_client_order 19110 19110 0 0.00 6 79263 456 0 0
sp_customer_reserve 450312 450312 0 0.00 1 110912 262 0 0
sp_fill_shopping_cart 29329 29329 0 0.00 1 11978 71 0 0
sp_fill_shopping_cart_clo_res 450295 450295 0 0.00 0 18222 18 0 0
sp_get_clo_for_invoice 4645 4645 0 0.00 0 24712 317 0 0
sp_kill_cost_storno 15793 15793 0 0.00 0 23584 70 0 0
sp_kill_qstorno_ret_qs2qd 1098399 1098399 0 0.00 0 33282 41 0 0
sp_kill_qty_storno 578138 578138 0 0.00 0 33289 94 0 0
sp_lock_selected_doc 19334 19334 0 0.00 0 9721 7 0 0
sp_make_cost_storno 26300 26300 0 0.00 0 17123 79 0 0
sp_multiply_rows_for_pdistr 26300 26300 0 0.00 0 9371 39 0 0
sp_multiply_rows_for_qdistr 19110 19110 0 0.00 0 79249 173 0 0
sp_pay_from_customer 6804 6804 0 0.00 6 19227 185 0 0
sp_pay_to_supplier 5516 5516 0 0.00 7 11508 195 0 0
sp_payment_common 12320 12320 0 0.00 2 9975 43 0 0
sp_qd_handle_on_cancel_clo 1628 1628 0 0.00 1 13930 310 0 0
sp_qd_handle_on_invoice_upd_sts 10113 10113 0 0.00 0 15547 475 0 0
sp_qd_handle_on_reserve_upd_sts 9335 9335 0 0.00 0 31201 66 0 0
sp_reserve_write_off 9335 9335 0 0.00 8 31219 273 0 0
sp_supplier_invoice 5135 5135 0 0.00 9 73038 2875 0 0
sp_supplier_order 5090 5090 0 0.00 8 42608 1283 0 0
srv_aggregate_perf_data 107 107 0 0.00 2410 164207 16894 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 692 171127 40770 0 0
srv_find_qd_qs_mism 1025101 1025101 0 0.00 0 36270 11 0 0
srv_make_invnt_saldo 421 421 0 0.00 69 71437 4203 0 0
srv_make_money_saldo 834 834 0 0.00 1 16016 397 0 0
srv_recalc_idx_stat 4 4 0 0.00 233410 462577 363305 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4698 4698 0 0.00 1 12794 835 0 0
t$perf-norm:sp_cancel_adding_invoice 356 356 0 0.00 1 6723 434 0 0
t$perf-norm:sp_cancel_client_order 1628 1628 0 0.00 0 5877 33 0 0
t$perf-norm:sp_cancel_customer_reserve 601 601 0 0.00 0 568 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 730 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 393 393 0 0.00 0 828 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 1135 36 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 75745 6170 0 0
t$perf-norm:sp_cancel_write_off 937 937 0 0.00 0 1553 19 0 0
t$perf-norm:sp_client_order 19110 19110 0 0.00 0 6836 32 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 1321 14 0 0
t$perf-norm:sp_pay_from_customer 6804 6804 0 0.00 0 3062 16 0 0
t$perf-norm:sp_pay_to_supplier 5516 5516 0 0.00 0 7598 23 0 0
t$perf-norm:sp_reserve_write_off 9335 9335 0 0.00 0 11162 22 0 0
t$perf-norm:sp_supplier_invoice 5135 5135 0 0.00 0 5178 30 0 0
t$perf-norm:sp_supplier_order 5090 5090 0 0.00 0 3431 29 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 244 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 2805 73 0 0
t$perf-norm:srv_make_invnt_saldo 421 421 0 0.00 0 2368 23 0 0
t$perf-norm:srv_make_money_saldo 834 834 0 0.00 0 726 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 201 57 0 0
v_add_invoice_to_stock 9562 9286 276 2.88 0 13517 29 0 276
v_all_customers 35354 35354 0 0.00 0 219 3 0 0
v_all_suppliers 28826 28826 0 0.00 0 223 4 0 0
v_all_wares 38220 38220 0 0.00 0 66 3 0 0
v_cancel_adding_invoice 712 712 0 0.00 0 12354 30 0 0
v_cancel_client_order 3256 3256 0 0.00 0 3784 12 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 12126 23 0 0
v_cancel_customer_reserve 1202 1202 0 0.00 0 1215 10 0 0
v_cancel_payment_to_supplier 786 786 0 0.00 0 10487 37 0 0
v_cancel_supplier_invoice 762 730 32 4.19 0 6547 41 0 32
v_cancel_supplier_order 834 834 0 0.00 0 13478 26 0 0
v_cancel_write_off 1874 1874 0 0.00 0 2490 22 0 0
v_max_id_clo_ord 5090 5090 0 0.00 0 278 4 0 0
v_max_id_clo_res 985 985 0 0.00 0 2232 13 0 0
v_max_id_ord_sup 5129 5129 0 0.00 0 3996 5 0 0
v_max_non_paid_invoice 1322 1322 0 0.00 0 3062 9 0 0
v_max_non_paid_realizn 3451 3451 0 0.00 0 649 6 0 0
v_min_id_clo_ord 5090 5090 0 0.00 0 5271 9 0 0
v_min_id_clo_res 985 985 0 0.00 0 3037 11 0 0
v_min_id_ord_sup 5129 5129 0 0.00 0 2574 7 0 0
v_min_non_paid_invoice 5516 5516 0 0.00 0 5258 16 0 0
v_min_non_paid_realizn 6804 6804 0 0.00 0 9965 18 0 0
v_our_firm 9572 9572 0 0.00 0 60 4 0 0
v_reserve_write_off 18670 18670 0 0.00 0 13479 14 0 0
x_lock_depdocs_on_canc_invoice 11138 11138 0 0.00 0 14651 131 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 2544 300 0 0
x_make_qstorno_1000_1200 5090 5090 0 0.00 3 42596 1130 0 0
x_make_qstorno_1200_2000 5135 5135 0 0.00 4 72184 2691 0 0
x_make_qstorno_2100_3300 450312 450312 0 0.00 0 110903 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-06-2022 04:39:32 to 25-06-2022 04:39:36.

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
02:07:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 9961 8192000000 9705311920 9999171584 783397760 8.728 100 89 178 0 813531024 7596992 22536272
02:09:39 18630 8192000000 9748426208 10051108864 822686144 9.126 100 90 180 0 855780880 11441072 21653664
02:11:06 24441 8192000000 9789527760 10107097088 861151920 9.512 100 85 170 0 897153680 16382880 19618880
02:12:36 10558 8192000000 9851498480 10183254016 914743280 10.044 100 82 164 1 958559344 25936000 17880064
02:13:54 6284 8192000000 9902775552 10247131136 952828304 10.419 100 80 158 2 1021068448 51639120 16601024
02:15:03 3697 8192000000 9961464096 10311819264 987458800 10.757 100 92 181 0 1079475488 72758112 19258576
02:16:09 1475 8192000000 9996990864 10357862400 1009487568 10.970 100 92 172 1 1113615056 86317744 17809744
02:17:13 1125 8192000000 10021112720 10385022976 1032201664 11.190 100 95 183 0 1140378640 89999504 18177472
02:18:17 934 8192000000 10003750448 10373713920 1052834880 11.388 100 94 180 0 1124266208 52943536 18487792
02:19:19 1603 8192000000 10031033776 10408194048 1061879584 11.474 100 91 171 0 1153327408 75034736 16413088
02:20:23 1454 8192000000 10046609664 10424844288 1074607424 11.596 100 92 177 1 1164819856 73356256 16856176
02:21:25 1274 8192000000 10068535008 10453581824 1074271552 11.593 100 96 183 1 1188312672 95404928 18636192
02:22:28 1004 8192000000 10058324960 10446872576 1083524160 11.681 100 95 188 0 1179701920 77818880 18358880
02:23:31 2906 8192000000 10072891136 10456010752 1090365344 11.746 100 87 171 1 1195433760 87736272 17332144
02:24:34 8902 8192000000 10031000448 10419798016 1093353472 11.775 100 66 131 0 1161020320 54170448 13496400
02:25:43 3220 8192000000 10078476864 10469281792 1100749888 11.845 100 75 148 1 1197330224 81104784 15475552
02:26:47 1027 8192000000 10092216784 10477481984 1098199776 11.821 100 96 190 1 1213896672 96921664 18775232
02:27:50 1100 8192000000 10058446336 10445479936 1090694640 11.749 100 92 173 5 1182205776 72566304 18944832
02:28:52 1322 8192000000 10066482896 10452111360 1084446752 11.690 100 98 188 0 1185899680 82557520 18895408
02:29:54 1950 8192000000 10029418752 10418663424 1064545632 11.500 98 68 130 1 1151518352 73277104 13695616
02:30:57 958 8192000000 10052100256 10439057408 1079974096 11.647 100 90 168 2 1175753472 77228576 18550800
02:31:59 1313 8192000000 10032093088 10420609024 1066178512 11.516 99 70 128 5 1155297856 75510208 13609136
02:33:01 1542 8192000000 10016355072 10400288768 1063969632 11.494 100 82 149 2 1136174320 56145648 16059040
02:34:03 3039 8192000000 10002307312 10382721024 1041467136 11.279 99 77 147 3 1120673728 63996912 15209680
02:35:07 941 8192000000 9998709360 10370441216 1029426320 11.163 100 83 162 2 1119258048 72657216 17174512
02:36:09 6438 8192000000 9986910032 10368724992 1027127616 11.141 99 55 111 0 1107543488 68402976 12012896
02:37:18 16189 8192000000 9999469984 10395394048 1039518800 11.260 100 53 103 1 1107382560 57736112 10127648
02:38:44 1151 8192000000 9972322464 10358362112 1041850928 11.282 99 64 119 3 1089226640 35198864 12176848
02:39:51 2145 8192000000 10013654576 10397925376 1062738256 11.483 100 78 149 1 1136723888 57632736 16352896
02:40:57 10023 8192000000 10050638400 10443730944 1077860144 11.627 100 56 108 0 1169345696 80213360 11272192
02:42:10 17938 8192000000 10080456528 10472558592 1087550528 11.719 100 66 116 1 1202254416 102258176 12445712
02:43:33 1322 8192000000 10134193984 10531454976 1109731792 11.930 100 75 139 2 1256728976 131605872 15391312
02:44:38 1675 8192000000 10194745808 10581897216 1117142640 12.000 100 97 188 1 1309292704 172212560 19937504
02:45:42 2018 8192000000 10198664016 10592894976 1134136704 12.160 100 83 160 0 1312133680 160012752 17984224
02:46:51 1141 8192000000 10258434544 10647945216 1152847744 12.336 100 86 165 1 1369158192 198799888 17510560
02:47:55 1018 8192000000 10309347936 10704826368 1162206896 12.424 100 91 174 0 1424663984 243986864 18470224
02:49:02 1059 8192000000 10311285760 10729095168 1169189776 12.489 100 94 182 1 1429552320 239602640 20759904
02:50:06 1195 8192000000 10342637840 10747195392 1178988128 12.581 100 96 185 0 1447723952 248430240 20305584
02:51:12 1812 8192000000 10385482592 10799796224 1182481568 12.613 100 92 181 0 1496925872 293642528 20801776
02:52:17 2773 8192000000 10424804688 10832527360 1194065376 12.721 100 84 165 3 1529714800 316018048 19631376
02:53:25 915 8192000000 10472846224 10898952192 1208577536 12.856 100 100 194 0 1581196240 348352336 24266368
02:54:29 1058 8192000000 10493260384 10910085120 1212132448 12.889 100 94 184 0 1601698704 367137984 22428272
02:55:32 964 8192000000 10511094944 10932133888 1214944128 12.915 100 97 188 1 1618486704 381402080 22140496
02:56:35 1039 8192000000 10582611568 11012919296 1215654224 12.921 100 98 191 0 1686060480 447663344 22742912
02:57:37 5151 8192000000 10567148352 10995585024 1205397136 12.826 100 65 126 1 1678189376 456544608 16247632
02:58:45 687 8192000000 10587239376 11011518464 1202497616 12.800 100 90 181 0 1693512800 470105568 20909616
02:59:47 4907 8192000000 10573747264 11046457344 1208395840 12.854 100 74 148 1 1682649728 456143552 18110336
03:00:57 10136 8192000000 10556298752 11002126336 1194335184 12.724 100 52 103 0 1661319088 452039872 14944032
03:02:09 17524 8192000000 10567596592 11070222336 1177603856 12.568 99 41 82 0 1674544800 485396208 11544736
03:03:30 10753 8192000000 10413140384 10953297920 1194210032 12.723 100 40 78 0 1529888880 325654368 10024480
03:04:42 22429 8192000000 10302809376 10806362112 1148568928 12.296 100 25 50 0 1449701440 294236944 6895568
03:06:39 16587 8192000000 10138888176 10609119232 1106926240 11.903 98 53 105 1 1218481504 99270816 12284448
03:08:02 19698 8192000000 10170665216 10617004032 1139896208 12.215 100 31 62 0 1250190320 103491584 6802528
03:09:24 75973 8192000000 10076693552 10575679488 1107076768 11.905 98 6 12 0 1170017744 61568016 1372960
03:11:43 70828 8192000000 10052833344 10510618624 1090604736 11.748 96 23 46 2 1147036288 51327568 5103984
03:13:55 81315 8192000000 10071478400 10562187264 1126050480 12.084 100 24 46 4 1185188960 54195264 4943216
03:16:18 48627 8192000000 10118730752 10567401472 1147099760 12.282 100 26 50 0 1211931392 59228256 5603376
03:18:10 100720 8192000000 10084676432 10564923392 1117968784 12.008 97 15 29 0 1159680848 39471744 2240320
03:21:25 47649 8192000000 10088621680 10533203968 1135740864 12.175 99 23 46 1 1168554640 28162624 4651152
03:23:17 28989 8192000000 10110624096 10574041088 1139863424 12.214 99 25 50 1 1206907136 60631152 6412560
03:24:48 41347 8192000000 10134389712 10582245376 1157459264 12.379 100 28 56 2 1224899920 61492064 5948592
03:26:34 51297 8192000000 10192820304 10644303872 1122277696 12.049 97 22 44 1 1248619584 121045776 5296112
03:28:25 171109 8192000000 10247210448 10757611520 1154515312 12.352 99 23 46 1 1319570704 159517952 5537440
03:33:32 69427 8192000000 10100160624 10591203328 1129747936 12.119 98 18 34 1 1176049536 42608880 3692720
03:35:42 21913 8192000000 10091092624 10522501120 1121796224 12.044 98 52 102 1 1172709200 40554400 10358576
03:38:05 86682 8192000000 10097979552 10552901632 1086397184 11.708 96 25 50 0 1162513232 70819040 5297008
03:40:41 139816 8192000000 10110309984 10560503808 1073931792 11.590 94 33 66 2 1121787984 41002752 6853440
03:44:05 17767 8192000000 10155518960 10588831744 1141805040 12.233 100 42 81 2 1236681200 86871024 8005136
03:45:32 110516 8192000000 10112407952 10552356864 1124714912 12.072 99 33 65 0 1188078608 56861264 6502432
03:48:26 48539 8192000000 10119796400 10569326592 1136871552 12.186 99 44 83 3 1211999296 66280128 8847616
03:50:14 57802 8192000000 10123403248 10583044096 1145683808 12.269 100 13 26 1 1234294512 85939376 2671328
03:52:45 69721 8192000000 10082095136 10559401984 1145401712 12.266 100 13 25 0 1193831664 46186576 2243376
03:55:00 20370 8192000000 10067829904 10507939840 1118708496 12.015 99 33 62 0 1154687072 29429312 6549264
03:56:38 19541 8192000000 10098269584 10545496064 1118323808 12.011 99 45 87 5 1186188560 58309200 9555552
03:58:03 47021 8192000000 10142614288 10593529856 1134973872 12.168 99 30 60 0 1217782640 76829408 5979360
03:59:59 75306 8192000000 10102886544 10568249344 1078919344 11.637 95 20 40 0 1146133264 63051056 4162864
04:02:15 28572 8192000000 10112612160 10550661120 1126031408 12.084 99 35 70 3 1198470960 65114784 7324768
04:03:45 64630 8192000000 10137604816 10585554944 1138514976 12.202 99 35 69 0 1204598576 58953168 7130432
04:05:59 89109 8192000000 10083448384 10530115584 1129722592 12.119 99 23 46 0 1168118400 34100656 4295152
04:08:31 19347 8192000000 10117193040 10561245184 1143391216 12.247 100 47 90 0 1217191344 64181056 9619072
04:09:53 70514 8192000000 10101095280 10561409024 1133710592 12.156 99 8 16 0 1198155152 62832928 1611632
04:12:04 74890 8192000000 10094084096 10570108928 1123150656 12.057 98 5 10 1 1175616624 51013888 1452080
04:14:20 58636 8192000000 10104413648 10584580096 1132773568 12.148 99 16 32 1 1181104016 44542112 3788336
04:16:22 54729 8192000000 10134379184 10596892672 1143421536 12.248 99 21 41 1 1232364224 84673520 4269168
04:18:19 33941 8192000000 10160694320 10611220480 1131284608 12.133 98 35 70 0 1234978576 96405904 7288064
04:19:53 50152 8192000000 10101003120 10611318784 1129715792 12.119 99 17 34 1 1231983792 98801408 3466592
04:21:46 35165 8192000000 10085751280 10517385216 1110406576 11.936 98 47 88 1 1158211616 38577760 9227280
04:23:21 40316 8192000000 10130429296 10588672000 1129434048 12.116 99 49 97 2 1206244976 66272720 10538208
04:25:06 65327 8192000000 10100620976 10547789824 1097176672 11.811 97 44 88 0 1169734032 64133088 8424272
04:27:20 65788 8192000000 10123278688 10615439360 1121967440 12.046 99 24 48 2 1237086848 110170368 4949040
04:29:27 144622 8192000000 10080842480 10545188864 1101669584 11.853 97 18 36 0 1157542816 52532096 3341136
04:33:35 82219 8192000000 10088331072 10555027456 1103011136 11.866 97 11 22 1 1161077328 55776416 2289776
04:35:58 77761 8192000000 10075982320 10558676992 1103698576 11.873 98 18 34 1 1155222944 47148096 4376272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-06-2022 04:39:38 to 25-06-2022 04:39:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 25-06-2022 04:39:55 to 25-06-2022 04:39:56.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          212686
MON$OLDEST_ACTIVE               213809
MON$OLDEST_SNAPSHOT             213809
MON$NEXT_TRANSACTION            213809
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-30 07:30:08.6030 GMT
MON$PAGES                       2040672
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2787 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2787 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2787 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2787 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Sat Jun 25 04:37:26 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
:89: 
:90: mscs-test-161.budzdorov.ru    Sat Jun 25 04:37:37 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Sat Jun 25 04:37:40 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
--- end of diff output ---

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

25.06.22 04:39:58. There are 3 logs in /var/tmp/logs-oltp40 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-06-25 04:40:00 No severe PSQL-related problems occured
25.06.22 04:40:01. End of report.