Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.04.2022 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16221276 kB
MemAvailable 31474888 kB
Buffers 20 kB
Cached 15388492 kB
SwapCached 2648 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-04-22 04:37:17
DTS_END                         2022-04-22 04:37:17.0270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40546
TRN_ID                          221310
ATT_ID                          594
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: 8998.38 24669 1079806
customer order: creation 171.96 461 20635
customer order: refuse 14.75 362 1770
order to supplier: creation 45.56 1307 5467
order to supplier: removal 3.45 154743 414
invoice (draft): creation 45.92 2965 5510
invoice (draft): removal 49.16 1044 5899
invoice accept: apply 42.18 26150 5061
invoice accept: cancel 44.74 1256 5369
customer reserve: creation 3982.34 246 477881
customer reserve: removal 4306.02 97 516722
realization accept: apply 82.38 276 9886
realization accept: cancel 81.88 171 9826
payment from customer: creation 58.35 183 7002
payment from customer: removal 5.60 125 672
payment to supplier: creation 48.16 191 5779
payment to supplier: removal 3.22 101 386
service: total inventory turnovers 3.93 3985 471
service: total monetary turnovers 7.87 374 944
service: aggregate perf. data 0.90 18897 108
service: refresh index statistics 0.03 280453 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-04-2022 04:38:24 to 22-04-2022 04:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9775
TEST_TIME 2 7082
TEST_TIME 3 6067
TEST_TIME 4 6228
TEST_TIME 5 6005
TEST_TIME 6 6941
TEST_TIME 7 7124
TEST_TIME 8 7193
TEST_TIME 9 7501
TEST_TIME 10 7421
TEST_TIME 11 7297
TEST_TIME 12 7227
TEST_TIME 13 7123
TEST_TIME 14 7032
TEST_TIME 15 7102
TEST_TIME 16 7036
TEST_TIME 17 7216
TEST_TIME 18 7163
TEST_TIME 19 7132
TEST_TIME 20 7351
TEST_TIME 21 7618
TEST_TIME 22 7636
TEST_TIME 23 7657
TEST_TIME 24 8219
TEST_TIME 25 8739
TEST_TIME 26 8830
TEST_TIME 27 9067
TEST_TIME 28 9157
TEST_TIME 29 9618
TEST_TIME 30 9726
TEST_TIME 31 10087
TEST_TIME 32 10434
TEST_TIME 33 10665
TEST_TIME 34 10567
TEST_TIME 35 10835
TEST_TIME 36 10938
TEST_TIME 37 10871
TEST_TIME 38 10718
TEST_TIME 39 10563
TEST_TIME 40 10510
TEST_TIME 41 10529
TEST_TIME 42 10449
TEST_TIME 43 10329
TEST_TIME 44 10284
TEST_TIME 45 10260
TEST_TIME 46 10345
TEST_TIME 47 10297
TEST_TIME 48 10188
TEST_TIME 49 10165
TEST_TIME 50 10049
TEST_TIME 51 9895
TEST_TIME 52 9841
TEST_TIME 53 9916
TEST_TIME 54 9809
TEST_TIME 55 9716
TEST_TIME 56 9727
TEST_TIME 57 9656
TEST_TIME 58 9620
TEST_TIME 59 9678
TEST_TIME 60 9673
TEST_TIME 61 9578
TEST_TIME 62 9526
TEST_TIME 63 9546
TEST_TIME 64 9471
TEST_TIME 65 9468
TEST_TIME 66 9424
TEST_TIME 67 9441
TEST_TIME 68 9485
TEST_TIME 69 9446
TEST_TIME 70 9518
TEST_TIME 71 9481
TEST_TIME 72 9520
TEST_TIME 73 9486
TEST_TIME 74 9484
TEST_TIME 75 9504
TEST_TIME 76 9528
TEST_TIME 77 9600
TEST_TIME 78 9614
TEST_TIME 79 9495
TEST_TIME 80 9439
TEST_TIME 81 9355
TEST_TIME 82 9348
TEST_TIME 83 9416
TEST_TIME 84 9355
TEST_TIME 85 9308
TEST_TIME 86 9292
TEST_TIME 87 9215
TEST_TIME 88 9170
TEST_TIME 89 9185
TEST_TIME 90 9137
TEST_TIME 91 9117
TEST_TIME 92 9110
TEST_TIME 93 9106
TEST_TIME 94 9214
TEST_TIME 95 9174
TEST_TIME 96 9212
TEST_TIME 97 9235
TEST_TIME 98 9207
TEST_TIME 99 9180
TEST_TIME 100 9262
TEST_TIME 101 9329
TEST_TIME 102 9306
TEST_TIME 103 9286
TEST_TIME 104 9253
TEST_TIME 105 9203
TEST_TIME 106 9230
TEST_TIME 107 9198
TEST_TIME 108 9152
TEST_TIME 109 9106
TEST_TIME 110 9072
TEST_TIME 111 9046
TEST_TIME 112 9050
TEST_TIME 113 9064
TEST_TIME 114 9070
TEST_TIME 115 9089
TEST_TIME 116 9083
TEST_TIME 117 9067
TEST_TIME 118 9065
TEST_TIME 119 9068
TEST_TIME 120 9022

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-04-2022 04:38:28 to 22-04-2022 04:38: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 1519369 1519369 0 0.00 0 22326 14 0 0
doc_list_biud 1519369 1519369 0 0.00 0 47024 39 0 0
sp_add_invoice_to_stock 5225 5061 164 3.13 83 198054 26151 0 164
sp_cancel_adding_invoice 5369 5369 0 0.00 5 128775 1256 0 0
sp_cancel_client_order 1770 1770 0 0.00 5 10477 362 0 0
sp_cancel_customer_reserve 516722 516722 0 0.00 1 29962 97 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 4 7587 126 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 1776 101 0 0
sp_cancel_supplier_invoice 5917 5899 18 0.30 3 47074 1044 0 18
sp_cancel_supplier_order 414 414 0 0.00 13 1431449 154744 0 0
sp_cancel_write_off 9826 9826 0 0.00 2 14103 172 0 0
sp_client_order 20635 20635 0 0.00 6 28770 461 0 0
sp_customer_reserve 477881 477881 0 0.00 1 94787 246 0 0
sp_fill_shopping_cart 31604 31604 0 0.00 1 20757 77 0 0
sp_fill_shopping_cart_clo_res 477864 477864 0 0.00 0 19712 17 0 0
sp_get_clo_for_invoice 5020 5020 0 0.00 0 15572 271 0 0
sp_kill_cost_storno 16253 16253 0 0.00 0 15628 80 0 0
sp_kill_qstorno_ret_qs2qd 1049583 1049583 0 0.00 0 47014 43 0 0
sp_kill_qty_storno 554904 554904 0 0.00 0 47017 98 0 0
sp_lock_selected_doc 20640 20640 0 0.00 0 9899 7 0 0
sp_make_cost_storno 27685 27685 0 0.00 0 18666 83 0 0
sp_multiply_rows_for_pdistr 27685 27685 0 0.00 0 7987 36 0 0
sp_multiply_rows_for_qdistr 20635 20635 0 0.00 0 11338 170 0 0
sp_pay_from_customer 7002 7002 0 0.00 5 15864 184 0 0
sp_pay_to_supplier 5779 5779 0 0.00 7 22613 192 0 0
sp_payment_common 12781 12781 0 0.00 2 14535 51 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 10454 285 0 0
sp_qd_handle_on_invoice_upd_sts 10387 10387 0 0.00 1 46885 488 0 0
sp_qd_handle_on_reserve_upd_sts 9886 9886 0 0.00 0 24739 65 0 0
sp_reserve_write_off 9886 9886 0 0.00 9 24806 277 0 0
sp_supplier_invoice 5510 5510 0 0.00 9 96995 2966 0 0
sp_supplier_order 5467 5467 0 0.00 7 50753 1308 0 0
srv_aggregate_perf_data 108 108 0 0.00 2684 191643 18898 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 708 144137 36199 0 0
srv_find_qd_qs_mism 1030226 1030226 0 0.00 0 18660 11 0 0
srv_make_invnt_saldo 471 471 0 0.00 63 69758 3986 0 0
srv_make_money_saldo 944 944 0 0.00 1 18545 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 83334 380807 280454 0 0
t$perf-abend:sp_add_invoice_to_stock 164 164 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5061 5061 0 0.00 1 11034 857 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 9404 475 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 1231 23 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1710 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 381 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 3037 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 1944 36 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 100266 6436 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 777 15 0 0
t$perf-norm:sp_client_order 20635 20635 0 0.00 0 4441 35 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 2652 17 0 0
t$perf-norm:sp_pay_from_customer 7002 7002 0 0.00 0 3157 17 0 0
t$perf-norm:sp_pay_to_supplier 5779 5779 0 0.00 0 6310 23 0 0
t$perf-norm:sp_reserve_write_off 9886 9886 0 0.00 0 7929 22 0 0
t$perf-norm:sp_supplier_invoice 5510 5510 0 0.00 0 4071 29 0 0
t$perf-norm:sp_supplier_order 5467 5467 0 0.00 0 5919 31 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 3393 41 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 830 17 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 3119 23 0 0
t$perf-norm:srv_make_money_saldo 944 944 0 0.00 0 2600 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 193 96 0 0
v_add_invoice_to_stock 10364 10036 328 3.16 0 9414 25 0 328
v_all_customers 37920 37920 0 0.00 0 133 3 0 0
v_all_suppliers 30858 30858 0 0.00 0 67 4 0 0
v_all_wares 41270 41270 0 0.00 0 212 3 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 2732 20 0 0
v_cancel_client_order 3540 3540 0 0.00 0 4373 12 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 5403 13 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 6654 20 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 830 10 0 0
v_cancel_supplier_invoice 890 854 36 4.04 0 1582 26 0 36
v_cancel_supplier_order 828 828 0 0.00 0 6522 16 0 0
v_cancel_write_off 1946 1946 0 0.00 0 2158 20 0 0
v_max_id_clo_ord 5467 5467 0 0.00 0 2208 5 0 0
v_max_id_clo_res 940 940 0 0.00 0 1303 12 0 0
v_max_id_ord_sup 5502 5502 0 0.00 0 6749 10 0 0
v_max_non_paid_invoice 1319 1319 0 0.00 0 709 7 0 0
v_max_non_paid_realizn 3513 3513 0 0.00 0 872 6 0 0
v_min_id_clo_ord 5467 5467 0 0.00 0 2991 8 0 0
v_min_id_clo_res 940 940 0 0.00 0 1219 11 0 0
v_min_id_ord_sup 5502 5502 0 0.00 0 5616 12 0 0
v_min_non_paid_invoice 5779 5779 0 0.00 0 9248 20 0 0
v_min_non_paid_realizn 7002 7002 0 0.00 0 14526 26 0 0
v_our_firm 10328 10328 0 0.00 0 220 4 0 0
v_reserve_write_off 19772 19772 0 0.00 0 6552 11 0 0
x_lock_depdocs_on_canc_invoice 10841 10841 0 0.00 0 14130 117 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 15800 390 0 0
x_make_qstorno_1000_1200 5467 5467 0 0.00 3 50737 1155 0 0
x_make_qstorno_1200_2000 5510 5510 0 0.00 4 96850 2749 0 0
x_make_qstorno_2100_3300 477881 477881 0 0.00 0 94779 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-04-2022 04:38:32 to 22-04-2022 04:38: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:20 8 8192000000 8805694176 8819699712 9941920 0.121 4 2 3 0 10118080 88352 87808
02:08:21 21790 8192000000 9731083488 10033831936 811149536 9.009 100 89 176 1 840593520 7736864 21707120
02:09:51 19247 8192000000 9766311968 10077290496 844714304 9.347 100 92 184 0 879388640 12940464 21733872
02:11:14 15025 8192000000 9796046192 10116182016 866168752 9.562 100 78 156 1 904829664 20417056 18243856
02:12:34 11471 8192000000 9857182288 10193002496 910148224 9.999 100 74 148 0 964627536 38666336 15812976
02:13:54 4041 8192000000 9918543968 10259341312 943861120 10.331 100 85 164 1 1031225728 69437584 17927024
02:15:03 2247 8192000000 9945651872 10298335232 987465552 10.757 100 84 163 0 1062548848 58868816 16214480
02:16:08 1826 8192000000 9990358960 10353065984 1015039856 11.024 100 83 156 1 1112968080 82650128 15278096
02:17:12 1396 8192000000 10024278192 10397782016 1043271984 11.296 100 99 185 1 1140351408 78167952 18911472
02:18:14 996 8192000000 10046761088 10419408896 1061943152 11.475 100 98 186 0 1170693584 90862336 17888096
02:19:17 1287 8192000000 10041060064 10422235136 1065528368 11.509 100 97 191 1 1160254432 75668432 19057632
02:20:20 1064 8192000000 10059439808 10441908224 1069091440 11.543 100 91 180 1 1178717120 91011200 18614480
02:21:22 2520 8192000000 10037179664 10425069568 1076095472 11.610 100 74 137 3 1158839840 68012560 14731808
02:22:25 2631 8192000000 10027392448 10415026176 1083188720 11.678 100 81 156 0 1145821488 47698928 14933840
02:23:29 1541 8192000000 10041743424 10425458688 1085695728 11.702 100 95 181 3 1161157104 57480560 17980816
02:24:31 1316 8192000000 10089183760 10474504192 1093866560 11.779 100 99 191 2 1211148224 98649824 18631840
02:25:33 1095 8192000000 10075783392 10468806656 1102212816 11.859 100 95 184 0 1191570800 70005120 19352864
02:26:35 1308 8192000000 10050529520 10439077888 1095501120 11.795 100 95 185 0 1169025360 55190224 18334016
02:27:37 1207 8192000000 10059129056 10447360000 1091269648 11.755 99 96 185 0 1178258272 68379328 18609296
02:28:40 1565 8192000000 10040104608 10438156288 1084470800 11.690 99 79 147 2 1167666752 67433856 15762096
02:29:42 1043 8192000000 10038622240 10420428800 1066107824 11.515 99 92 178 0 1160970608 76543024 18319760
02:30:44 1111 8192000000 10021270160 10403733504 1072424048 11.575 100 82 159 2 1142141136 53481584 16235504
02:31:46 1769 8192000000 10015138976 10400563200 1062265184 11.478 100 78 154 0 1144031616 65227568 16538864
02:32:49 978 8192000000 10002191296 10379194368 1053860832 11.398 100 92 171 0 1126955808 55101872 17993104
02:33:50 1070 8192000000 10015077888 10388389888 1049274272 11.354 99 92 182 0 1137174000 69196368 18703360
02:34:52 4230 8192000000 9987834784 10374660096 1059367648 11.450 100 77 138 5 1112016784 38566960 14082176
02:35:58 844 8192000000 9944926576 10316570624 1016699744 11.040 100 79 144 2 1066827200 34780688 15346768
02:36:59 1382 8192000000 10001519936 10376511488 1052656000 11.386 100 92 178 1 1128941760 57825216 18460544
02:38:07 1864 8192000000 9992271968 10368937984 1057275168 11.430 100 74 128 1 1111870704 42996144 11599392
02:39:16 5503 8192000000 10028181536 10408964096 1085176832 11.697 100 76 142 1 1151675376 51614240 14884304
02:40:54 3673 8192000000 10079956048 10460041216 1090872256 11.751 100 82 157 1 1193630304 86465808 16292240
02:42:07 2314 8192000000 10117471856 10502909952 1103378384 11.870 100 86 159 1 1232213120 112624336 16210400
02:43:15 1131 8192000000 10165716416 10557911040 1106210768 11.897 100 94 183 3 1283954896 157651152 20092976
02:44:19 702 8192000000 10139271376 10526773248 1129229056 12.114 100 97 175 3 1251269360 104464464 17575840
02:45:24 1588 8192000000 10184993856 10582626304 1140825344 12.223 100 79 157 1 1300619376 143306352 16487680
02:46:30 999 8192000000 10181718048 10594160640 1156069168 12.366 100 79 151 0 1294976192 122721264 16185760
02:47:35 1026 8192000000 10219377440 10614652928 1160238768 12.406 100 91 174 2 1329286528 149845024 19202736
02:48:43 1776 8192000000 10272654832 10664648704 1174823568 12.542 100 94 186 0 1378721952 182775856 21122528
02:49:50 1437 8192000000 10329833872 10730852352 1181924992 12.608 100 88 171 0 1436491920 235526880 19040048
02:50:56 3200 8192000000 10345356096 10751492096 1178289648 12.574 99 80 159 0 1444403392 248253184 17860560
02:52:03 924 8192000000 10400978960 10800521216 1176829648 12.561 99 95 178 1 1507766480 310001168 20935664
02:53:09 1924 8192000000 10442201232 10847092736 1199447360 12.771 100 92 176 2 1550032880 329460464 21125056
02:54:14 1749 8192000000 10478707280 10888704000 1195061792 12.730 100 95 188 1 1587739040 370544128 22133120
02:55:19 913 8192000000 10522834448 10930188288 1203165424 12.806 100 99 196 0 1632102464 405714448 23222592
02:56:24 1207 8192000000 10546648160 10975404032 1202873296 12.803 100 95 190 0 1653736304 427747520 23115488
02:57:28 1000 8192000000 10584602064 11015536640 1193181584 12.713 100 98 193 0 1691138336 473418672 24538080
02:58:31 7061 8192000000 10568919168 11004243968 1192114976 12.703 100 68 132 1 1673344496 464815840 16413680
02:59:54 7916 8192000000 10576178048 11022655488 1180564416 12.595 100 57 111 1 1675264144 480269104 14430624
03:01:10 1119 8192000000 10573598432 11045986304 1181745840 12.606 100 77 152 1 1679969600 478886960 19336800
03:02:16 2816 8192000000 10471835200 10954018816 1172130656 12.517 100 73 142 1 1575287872 386634432 16522784
03:03:20 12083 8192000000 10441315488 10879639552 1150868640 12.318 99 43 82 1 1546843968 385887040 10088288
03:04:36 1612 8192000000 10419648624 10840739840 1161405808 12.416 100 82 162 1 1522867584 343692784 17768992
03:05:40 9408 8192000000 10405861024 10894143488 1163316192 12.434 100 32 64 0 1553848896 382566736 7965968
03:06:51 19249 8192000000 10309327024 10774773760 1143317248 12.247 99 26 52 0 1434374688 284648736 6408704
03:08:22 20251 8192000000 10290310240 10812092416 1146442512 12.276 100 36 71 2 1427913344 272867328 8603504
03:09:43 67484 8192000000 10203453840 10654523392 1148017632 12.291 100 18 36 2 1356607552 204024560 4565360
03:11:55 57772 8192000000 10125201072 10655895552 1116429904 11.993 98 31 60 0 1277700112 154649696 6620512
03:13:56 61908 8192000000 10073417008 10542350336 1076506272 11.614 95 12 24 0 1146986528 67869328 2610928
03:16:01 119091 8192000000 10087171952 10559025152 1103174048 11.868 98 20 38 1 1186128288 78828784 4125456
03:19:01 143698 8192000000 10113746288 10613497856 1116938320 11.998 98 18 36 1 1184295344 63808112 3548912
03:22:30 62762 8192000000 10089381712 10600263680 1141806944 12.233 100 21 40 1 1221409536 75112128 4490464
03:24:45 35438 8192000000 10068513280 10505318400 1107853376 11.912 97 32 55 4 1144789264 31943120 4992768
03:26:21 37601 8192000000 10081922784 10521157632 1104486736 11.880 98 25 49 0 1142278832 32888304 4903792
03:28:04 144137 8192000000 10083469008 10532700160 1129057408 12.112 100 36 63 2 1175566848 38711152 7798288
03:31:30 101456 8192000000 10124514112 10568982528 1132518656 12.145 99 14 28 0 1178652288 42865760 3267872
03:34:12 12853 8192000000 10116328608 10563899392 1144308384 12.256 100 29 56 1 1215426400 65079056 6038960
03:35:29 23121 8192000000 10174680864 10633601024 1143200160 12.246 99 46 92 0 1264875632 111624800 10050672
03:36:56 96365 8192000000 10110494800 10600824832 1135811456 12.176 99 13 26 0 1225591824 87043328 2737040
03:40:38 53405 8192000000 10056515600 10567163904 1091549040 11.757 96 9 18 1 1130060672 36992128 1519504
03:42:32 73336 8192000000 10089034912 10526494720 1081678144 11.663 95 19 38 2 1124439536 38830016 3931376
03:44:52 46749 8192000000 10098126720 10562084864 1102025952 11.857 97 27 53 1 1175483072 67793664 5663456
03:46:45 22878 8192000000 10083169776 10537517056 1115434704 11.984 99 43 86 0 1176315200 52342848 8537648
03:48:11 38204 8192000000 10089913936 10525024256 1111930912 11.951 98 28 52 1 1165350944 48194208 5225824
03:49:50 18252 8192000000 10079973008 10506588160 1120757136 12.034 99 42 82 1 1161838688 31649904 9431648
03:51:12 24427 8192000000 10114899680 10540396544 1113813184 11.969 98 37 74 1 1194061984 72855408 7393392
03:52:43 44305 8192000000 10131432816 10586894336 1124050288 12.065 99 27 53 0 1223681104 93938928 5691888
03:54:41 113522 8192000000 10045050592 10526507008 1075240512 11.602 95 16 32 0 1127050288 48524976 3284800
03:57:45 66420 8192000000 10120964096 10557538304 1125932480 12.083 100 29 59 0 1179043392 46901072 6209840
04:00:07 71196 8192000000 10060087392 10570674176 1096582848 11.805 97 11 23 1 1146115264 47408816 2123600
04:02:38 38475 8192000000 10076107568 10526785536 1096154800 11.801 98 34 65 2 1142348160 39411440 6781920
04:04:21 76945 8192000000 10066895520 10523312128 1106768256 11.902 99 11 21 0 1161777776 52485712 2523808
04:06:46 69133 8192000000 10066756352 10525810688 1076126784 11.611 96 16 32 0 1126313744 46834880 3352080
04:09:03 57174 8192000000 10095081520 10536542208 1063272096 11.488 95 19 39 0 1148879280 81340640 4266544
04:11:01 67787 8192000000 10061788016 10491002880 1091389408 11.756 98 30 59 1 1152004224 54544768 6070048
04:13:10 28664 8192000000 10122351728 10593038336 1130487504 12.126 100 44 88 0 1219342128 79873136 8981488
04:14:40 22908 8192000000 10094116160 10540634112 1127288512 12.096 100 47 93 0 1188002624 50645008 10069104
04:16:04 12121 8192000000 10167695456 10591375360 1142916160 12.243 100 66 132 1 1251255568 95095680 13243728
04:17:19 38456 8192000000 10127540400 10613460992 1106579840 11.900 98 31 62 0 1236648016 123525968 6542208
04:19:07 9893 8192000000 10061433184 10487779328 1116738832 11.996 99 49 95 0 1149871840 22427168 10705840
04:20:21 7436 8192000000 10122907648 10533892096 1127744736 12.100 99 76 138 5 1197226384 57072448 12409200
04:21:32 139254 8192000000 10060426752 10564280320 1108710784 11.920 98 27 54 1 1195607856 81231040 5666032
04:25:01 62101 8192000000 10059291168 10508365824 1105092160 11.886 98 11 22 0 1150296544 42922704 2281680
04:27:03 65790 8192000000 10105448752 10550480896 1115796400 11.987 98 20 40 1 1187675872 67459824 4419648
04:29:16 46048 8192000000 10116316864 10568736768 1117134976 12.000 99 16 32 0 1198282432 77154080 3993376
04:31:02 12604 8192000000 10101108992 10526191616 1120544800 12.032 99 49 94 2 1191621520 61435424 9641296
04:32:15 38137 8192000000 10095887200 10544668672 1103048672 11.867 97 44 88 0 1159839968 47972112 8819184
04:34:00 46836 8192000000 10083582608 10532225024 1095982272 11.800 97 37 74 0 1162524096 58996608 7545216
04:35:49 43117 8192000000 10131544816 10593660928 1121811040 12.044 99 40 80 2 1239316848 109222208 8283600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 164 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-04-2022 04:38:55 to 22-04-2022 04:38:57.

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          217419
MON$OLDEST_ACTIVE               221685
MON$OLDEST_SNAPSHOT             221685
MON$NEXT_TRANSACTION            221685
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                       2071328
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:473: 
:474: mscs-test-161.budzdorov.ru    Fri Apr 22 04:37:29 2022
:475:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:476: 
--- end of diff output ---

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

22.04.22 04:38: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-04-22 04:39:01 No severe PSQL-related problems occured
22.04.22 04:39:02. End of report.