Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.07.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 16007920 kB
MemAvailable 30989880 kB
Buffers 20 kB
Cached 15614204 kB
SwapCached 2500 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-07-22 04:37:19
DTS_END                         2022-07-22 04:37:19.5360
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/44842
TRN_ID                          214449
ATT_ID                          599
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: 8879.78 25325 1065574
customer order: creation 161.97 488 19436
customer order: refuse 13.39 407 1607
order to supplier: creation 42.87 1378 5144
order to supplier: removal 3.19 151802 383
invoice (draft): creation 43.67 2919 5240
invoice (draft): removal 50.55 976 6066
invoice accept: apply 39.60 24345 4752
invoice accept: cancel 45.45 1089 5454
customer reserve: creation 3731.02 229 447722
customer reserve: removal 4472.73 85 536728
realization accept: apply 79.01 249 9481
realization accept: cancel 75.25 145 9030
payment from customer: creation 55.48 157 6658
payment from customer: removal 5.28 100 634
payment to supplier: creation 45.91 169 5509
payment to supplier: removal 3.07 118 368
service: total inventory turnovers 4.07 3524 488
service: total monetary turnovers 6.38 344 765
service: aggregate perf. data 0.88 17509 105
service: refresh index statistics 0.03 300465 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-07-2022 04:38:25 to 22-07-2022 04:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3551
TEST_TIME 2 4599
TEST_TIME 3 4643
TEST_TIME 4 4912
TEST_TIME 5 4595
TEST_TIME 6 4994
TEST_TIME 7 5305
TEST_TIME 8 5426
TEST_TIME 9 5411
TEST_TIME 10 5988
TEST_TIME 11 6110
TEST_TIME 12 6271
TEST_TIME 13 6768
TEST_TIME 14 6914
TEST_TIME 15 7379
TEST_TIME 16 7505
TEST_TIME 17 7624
TEST_TIME 18 7835
TEST_TIME 19 8010
TEST_TIME 20 8280
TEST_TIME 21 8971
TEST_TIME 22 8859
TEST_TIME 23 9136
TEST_TIME 24 8943
TEST_TIME 25 8990
TEST_TIME 26 9553
TEST_TIME 27 10099
TEST_TIME 28 10248
TEST_TIME 29 10397
TEST_TIME 30 10403
TEST_TIME 31 10511
TEST_TIME 32 11004
TEST_TIME 33 10792
TEST_TIME 34 10660
TEST_TIME 35 10588
TEST_TIME 36 10346
TEST_TIME 37 10340
TEST_TIME 38 10193
TEST_TIME 39 10194
TEST_TIME 40 10074
TEST_TIME 41 10009
TEST_TIME 42 9990
TEST_TIME 43 10031
TEST_TIME 44 9949
TEST_TIME 45 9934
TEST_TIME 46 9869
TEST_TIME 47 9789
TEST_TIME 48 9699
TEST_TIME 49 9613
TEST_TIME 50 9649
TEST_TIME 51 9554
TEST_TIME 52 9518
TEST_TIME 53 9496
TEST_TIME 54 9535
TEST_TIME 55 9519
TEST_TIME 56 9471
TEST_TIME 57 9500
TEST_TIME 58 9601
TEST_TIME 59 9634
TEST_TIME 60 9606
TEST_TIME 61 9754
TEST_TIME 62 9756
TEST_TIME 63 9678
TEST_TIME 64 9769
TEST_TIME 65 9676
TEST_TIME 66 9594
TEST_TIME 67 9537
TEST_TIME 68 9463
TEST_TIME 69 9426
TEST_TIME 70 9412
TEST_TIME 71 9317
TEST_TIME 72 9300
TEST_TIME 73 9243
TEST_TIME 74 9267
TEST_TIME 75 9197
TEST_TIME 76 9149
TEST_TIME 77 9122
TEST_TIME 78 9063
TEST_TIME 79 9059
TEST_TIME 80 9055
TEST_TIME 81 9061
TEST_TIME 82 9025
TEST_TIME 83 9055
TEST_TIME 84 9102
TEST_TIME 85 9104
TEST_TIME 86 9223
TEST_TIME 87 9240
TEST_TIME 88 9248
TEST_TIME 89 9254
TEST_TIME 90 9258
TEST_TIME 91 9161
TEST_TIME 92 9108
TEST_TIME 93 9203
TEST_TIME 94 9144
TEST_TIME 95 9128
TEST_TIME 96 9066
TEST_TIME 97 9108
TEST_TIME 98 9095
TEST_TIME 99 9109
TEST_TIME 100 9115
TEST_TIME 101 9134
TEST_TIME 102 9115
TEST_TIME 103 9089
TEST_TIME 104 9135
TEST_TIME 105 9149
TEST_TIME 106 9154
TEST_TIME 107 9162
TEST_TIME 108 9217
TEST_TIME 109 9208
TEST_TIME 110 9135
TEST_TIME 111 9110
TEST_TIME 112 9081
TEST_TIME 113 9068
TEST_TIME 114 9035
TEST_TIME 115 9010
TEST_TIME 116 8999
TEST_TIME 117 9000
TEST_TIME 118 8971
TEST_TIME 119 8968
TEST_TIME 120 8906

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-07-2022 04:38:29 to 22-07-2022 04:38:32.

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 1477222 1477222 0 0.00 0 12330 11 0 0
doc_list_biud 1477210 1477210 0 0.00 0 76750 37 0 0
sp_add_invoice_to_stock 4852 4752 100 2.06 41 214806 24346 0 100
sp_cancel_adding_invoice 5454 5454 0 0.00 8 106740 1090 0 0
sp_cancel_client_order 1607 1607 0 0.00 6 25659 408 0 0
sp_cancel_customer_reserve 536728 536728 0 0.00 1 76760 85 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 4 2586 101 0 0
sp_cancel_pay_to_supplier 368 368 0 0.00 3 7145 119 0 0
sp_cancel_supplier_invoice 6074 6066 8 0.13 3 34855 976 0 8
sp_cancel_supplier_order 383 383 0 0.00 11 1119736 151803 0 0
sp_cancel_write_off 9030 9030 0 0.00 2 12353 146 0 0
sp_client_order 19436 19436 0 0.00 5 33009 488 0 0
sp_customer_reserve 447722 447722 0 0.00 0 75581 229 0 0
sp_fill_shopping_cart 29810 29810 0 0.00 1 11885 72 0 0
sp_fill_shopping_cart_clo_res 447686 447686 0 0.00 0 16844 19 0 0
sp_get_clo_for_invoice 4702 4702 0 0.00 0 22599 321 0 0
sp_kill_cost_storno 15486 15486 0 0.00 0 9994 59 0 0
sp_kill_qstorno_ret_qs2qd 1088935 1088935 0 0.00 0 76738 39 0 0
sp_kill_qty_storno 573447 573447 0 0.00 0 76748 89 0 0
sp_lock_selected_doc 19455 19455 0 0.00 0 7275 8 0 0
sp_make_cost_storno 26348 26348 0 0.00 0 12322 67 0 0
sp_multiply_rows_for_pdistr 26346 26346 0 0.00 0 9593 33 0 0
sp_multiply_rows_for_qdistr 19434 19434 0 0.00 0 12713 206 0 0
sp_pay_from_customer 6658 6658 0 0.00 5 12198 157 0 0
sp_pay_to_supplier 5509 5509 0 0.00 6 5969 170 0 0
sp_payment_common 12167 12167 0 0.00 2 8450 38 0 0
sp_qd_handle_on_cancel_clo 1607 1607 0 0.00 0 25604 335 0 0
sp_qd_handle_on_invoice_upd_sts 10154 10154 0 0.00 0 38590 476 0 0
sp_qd_handle_on_reserve_upd_sts 9479 9479 0 0.00 0 16797 65 0 0
sp_reserve_write_off 9481 9481 0 0.00 8 17285 250 0 0
sp_supplier_invoice 5240 5240 0 0.00 9 107741 2919 0 0
sp_supplier_order 5144 5144 0 0.00 8 36058 1379 0 0
srv_aggregate_perf_data 105 105 0 0.00 2575 144753 17509 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 792 134141 36337 0 0
srv_find_qd_qs_mism 1018762 1018762 0 0.00 0 9416 9 0 0
srv_make_invnt_saldo 488 488 0 0.00 55 117264 3524 0 0
srv_make_money_saldo 765 765 0 0.00 1 14313 345 0 0
srv_recalc_idx_stat 4 4 0 0.00 113913 538084 300465 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4757 4757 0 0.00 0 13521 787 0 0
t$perf-norm:sp_cancel_adding_invoice 358 358 0 0.00 1 6258 451 0 0
t$perf-norm:sp_cancel_client_order 1607 1607 0 0.00 0 1256 22 0 0
t$perf-norm:sp_cancel_customer_reserve 599 599 0 0.00 0 11804 41 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 5409 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 368 368 0 0.00 0 2274 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 1106 30 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 74430 6088 0 0
t$perf-norm:sp_cancel_write_off 951 951 0 0.00 0 2827 20 0 0
t$perf-norm:sp_client_order 19436 19436 0 0.00 0 5244 30 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 2820 15 0 0
t$perf-norm:sp_pay_from_customer 6658 6658 0 0.00 0 11080 20 0 0
t$perf-norm:sp_pay_to_supplier 5509 5509 0 0.00 0 11550 22 0 0
t$perf-norm:sp_reserve_write_off 9481 9481 0 0.00 0 29559 25 0 0
t$perf-norm:sp_supplier_invoice 5240 5240 0 0.00 0 10773 32 0 0
t$perf-norm:sp_supplier_order 5144 5144 0 0.00 0 5273 28 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 489 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 53 4 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 1523 17 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 2728 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 169 50 0 0
v_add_invoice_to_stock 9590 9390 200 2.08 0 4173 28 0 200
v_all_customers 35340 35340 0 0.00 0 65 3 0 0
v_all_suppliers 29154 29154 0 0.00 0 185 4 0 0
v_all_wares 38864 38864 0 0.00 0 49 3 0 0
v_cancel_adding_invoice 716 716 0 0.00 0 3324 24 0 0
v_cancel_client_order 3214 3214 0 0.00 0 3288 10 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 2124 13 0 0
v_cancel_customer_reserve 1198 1198 0 0.00 0 2283 11 0 0
v_cancel_payment_to_supplier 736 736 0 0.00 0 2345 15 0 0
v_cancel_supplier_invoice 778 762 16 2.05 0 1625 27 0 16
v_cancel_supplier_order 766 766 0 0.00 0 3591 20 0 0
v_cancel_write_off 1902 1902 0 0.00 0 2272 25 0 0
v_max_id_clo_ord 5140 5140 0 0.00 0 1843 5 0 0
v_max_id_clo_res 946 946 0 0.00 0 1301 12 0 0
v_max_id_ord_sup 5236 5236 0 0.00 0 5001 8 0 0
v_max_non_paid_invoice 1308 1308 0 0.00 0 110 7 0 0
v_max_non_paid_realizn 3539 3539 0 0.00 0 8445 10 0 0
v_min_id_clo_ord 5140 5140 0 0.00 0 6982 10 0 0
v_min_id_clo_res 946 946 0 0.00 0 8848 19 0 0
v_min_id_ord_sup 5236 5236 0 0.00 0 4295 11 0 0
v_min_non_paid_invoice 5509 5509 0 0.00 0 4315 12 0 0
v_min_non_paid_realizn 6658 6658 0 0.00 0 5365 15 0 0
v_our_firm 9760 9760 0 0.00 0 69 4 0 0
v_reserve_write_off 18958 18958 0 0.00 0 3470 11 0 0
x_lock_depdocs_on_canc_invoice 11139 11139 0 0.00 0 19781 113 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 8026 346 0 0
x_make_qstorno_1000_1200 5144 5144 0 0.00 2 36037 1239 0 0
x_make_qstorno_1200_2000 5240 5240 0 0.00 4 96412 2723 0 0
x_make_qstorno_2100_3300 447720 447720 0 0.00 0 75570 200 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-07-2022 04:38:33 to 22-07-2022 04:38:37.

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:23 6 8192000000 8798688560 8809926656 4473440 0.054 2 1 2 0 4572832 63616 35776
02:08:24 12871 8192000000 9705205136 9998262272 783548672 8.729 100 93 186 0 814765360 7974048 23242640
02:09:44 16177 8192000000 9754439920 10059636736 827068336 9.170 100 88 174 0 859446800 11467392 20911072
02:11:05 14713 8192000000 9784896176 10100477952 856106224 9.461 100 84 168 0 891388096 15558720 19723152
02:12:21 8273 8192000000 9836483696 10168033280 903814480 9.936 100 75 148 1 949193936 29424304 15955152
02:13:34 5105 8192000000 9903000944 10246553600 953193328 10.422 100 85 170 0 1022409568 50798576 18417664
02:14:43 3115 8192000000 9959226224 10309693440 986189232 10.744 100 86 170 1 1083805552 80751184 16865136
02:15:49 1838 8192000000 10002759168 10359468032 1013394832 11.008 100 95 187 1 1120267056 88181312 18690912
02:16:55 1117 8192000000 10041611296 10407821312 1027337504 11.143 100 95 187 0 1153048416 106998560 18712352
02:17:57 1068 8192000000 10017606192 10388889600 1050995600 11.370 100 97 185 2 1141128336 71720304 18412432
02:18:59 915 8192000000 10040252160 10420584448 1065588112 11.510 100 89 171 2 1163415344 80048800 17778432
02:20:01 1032 8192000000 10050451664 10427277312 1069345216 11.546 100 96 185 1 1171934080 83835376 18753488
02:21:04 1053 8192000000 10034332400 10419216384 1073911136 11.589 100 84 158 1 1164466464 73699152 16856176
02:22:06 1019 8192000000 10046588672 10429476864 1082529136 11.672 100 96 180 0 1179418832 78225552 18664144
02:23:09 1072 8192000000 10063313952 10450849792 1089612256 11.739 100 97 188 1 1183918848 75817680 18488912
02:24:12 1131 8192000000 10046409600 10434179072 1093160848 11.773 100 94 182 2 1167424976 56603952 17660176
02:25:13 7026 8192000000 10027450336 10422784000 1092946688 11.771 100 55 105 2 1155793184 52310320 10536176
02:26:21 984 8192000000 10058062144 10452127744 1097935200 11.818 100 80 152 2 1179522512 66117424 15469888
02:27:23 1212 8192000000 10068337840 10459418624 1103297280 11.869 100 84 159 2 1184146784 64571824 16277680
02:28:24 834 8192000000 10022283408 10414555136 1096408944 11.804 100 72 134 0 1146241472 35385728 14446800
02:29:26 1024 8192000000 10029499952 10415263744 1081689680 11.664 100 85 163 2 1151502736 53043552 16769504
02:30:27 1222 8192000000 10027129280 10406858752 1072265920 11.574 100 95 181 1 1146882512 56498640 18117952
02:31:29 838 8192000000 10025518064 10408460288 1072116560 11.572 99 90 168 1 1145979840 57228016 16635264
02:32:31 1407 8192000000 9956970128 10328096768 1017166416 11.045 100 88 158 2 1085184400 51893824 16124160
02:33:33 1960 8192000000 9980144384 10360623104 1039918592 11.264 100 82 158 0 1107151776 50992640 16240544
02:34:36 922 8192000000 10006249824 10382536704 1028350848 11.153 99 86 162 4 1131348656 86256368 16741440
02:35:38 936 8192000000 9998898864 10373496832 1046952192 11.331 100 93 184 1 1121566928 52965360 21649376
02:36:39 958 8192000000 9996405072 10370699264 1051744832 11.377 100 96 180 0 1114722720 44093728 18884160
02:37:43 1811 8192000000 9991702688 10367934464 1051209792 11.372 99 80 145 3 1113920816 47586288 15124736
02:38:55 7561 8192000000 10054307456 10439274496 1078153152 11.630 100 71 133 2 1173366608 81719984 13493472
02:40:07 5527 8192000000 10065256608 10445553664 1096190160 11.801 100 89 159 0 1182795120 71060096 15544864
02:41:18 7544 8192000000 10103724352 10491224064 1104143456 11.877 100 70 129 2 1227919632 110036304 13739872
02:42:28 992 8192000000 10153425968 10540777472 1119194912 12.019 100 86 166 1 1276131856 137807088 19129856
02:43:34 8178 8192000000 10157721904 10556633088 1120439136 12.031 100 67 130 1 1281157360 147028240 13689984
02:44:52 1030 8192000000 10186589264 10579652608 1136381328 12.181 100 85 157 1 1302803328 149149104 17272896
02:45:58 3833 8192000000 10233993120 10625163264 1148941072 12.300 100 87 165 3 1353560352 187008704 17610576
02:47:06 1032 8192000000 10266646192 10680643584 1160647184 12.409 100 93 176 1 1380249648 200490576 19111888
02:48:10 1532 8192000000 10295021376 10693013504 1167076128 12.469 100 94 178 0 1407744896 220318336 20350432
02:49:14 2552 8192000000 10317695392 10717052928 1163886656 12.440 100 95 180 3 1428601824 245294160 19421008
02:50:21 1420 8192000000 10360342944 10789814272 1177905504 12.571 100 98 192 1 1480278640 279931744 22441392
02:51:24 1180 8192000000 10353060672 10785492992 1186572384 12.651 100 95 182 2 1463993184 255918544 21502256
02:52:28 783 8192000000 10371916576 10803527680 1197410208 12.752 100 96 191 0 1479567872 260467328 21690336
02:53:31 1207 8192000000 10412776688 10820198400 1191241424 12.695 100 97 188 0 1520606752 307976240 21389088
02:54:36 825 8192000000 10477414240 10895949824 1194965984 12.730 100 96 190 2 1583235232 365423328 22845920
02:55:38 1070 8192000000 10447798304 10887868416 1190433120 12.687 100 84 162 1 1551855152 341513520 19908512
02:56:43 1162 8192000000 10411344224 10840498176 1179912000 12.589 99 83 156 1 1516441184 318140256 18388928
02:57:48 2624 8192000000 10437490720 10911600640 1196273104 12.742 100 67 134 0 1543993968 331144880 16575984
02:58:53 18240 8192000000 10369700128 10819006464 1191896000 12.701 100 30 60 0 1494558672 294644768 8017904
03:00:18 9875 8192000000 10404569696 10876903424 1197688640 12.755 100 45 90 0 1516527264 308000880 10837744
03:01:30 24385 8192000000 10407744208 10867679232 1190023440 12.684 100 30 58 0 1525208176 327342000 7842736
03:02:55 27500 8192000000 10361748560 10877771776 1170180176 12.499 99 29 56 0 1480076064 302537344 7358544
03:04:24 23182 8192000000 10272868720 10776055808 1162027312 12.422 99 34 66 1 1375265472 204334384 8903776
03:05:57 10559 8192000000 10210544400 10671632384 1155227696 12.359 99 36 69 1 1310299856 146964128 8108032
03:07:14 57051 8192000000 10202288656 10637234176 1093269888 11.774 94 21 39 0 1264996672 167440736 4286048
03:09:15 67433 8192000000 10064638992 10522648576 1114525216 11.975 97 17 28 0 1164167024 46751504 2890304
03:11:23 105222 8192000000 10109262944 10598776832 1105514624 11.890 97 26 50 0 1179356448 68722736 5119088
03:14:23 67389 8192000000 10111908864 10569654272 1128478640 12.107 99 27 52 0 1196750480 62787696 5484144
03:16:31 47338 8192000000 10085360496 10510045184 1059646496 11.453 94 26 52 0 1125522544 60205888 5670160
03:18:19 26725 8192000000 10101544256 10527866880 1106594064 11.900 99 51 98 1 1181411024 64066656 10750304
03:19:48 33639 8192000000 10088109312 10575257600 1106770224 11.902 99 19 38 0 1193314656 82242016 4302416
03:21:28 23787 8192000000 10063077184 10510684160 1080716816 11.654 97 20 38 1 1139972720 55111760 4144144
03:22:53 21573 8192000000 10062719600 10516824064 1096464496 11.804 99 14 21 2 1161682176 62796352 2421328
03:24:15 28310 8192000000 10032972736 10519937024 1114554688 11.976 100 24 39 1 1150296976 31123920 4618368
03:25:44 81413 8192000000 10073840784 10572914688 1109524032 11.928 99 17 32 0 1185444288 72354720 3565536
03:29:04 39562 8192000000 10111189168 10565812224 1100533520 11.843 97 41 80 0 1149425984 40768736 8123728
03:30:44 46057 8192000000 10131153968 10597347328 1132000896 12.140 100 23 46 0 1242249904 104957680 5291328
03:33:06 77504 8192000000 10178798224 10641063936 1109563328 11.928 98 30 57 0 1261517616 145144304 6809984
03:35:29 21488 8192000000 10070481984 10535944192 1118272416 12.011 99 29 57 1 1198273024 74362064 5638544
03:36:52 19034 8192000000 10156462176 10587996160 1139979984 12.215 100 42 81 1 1236028912 88079632 7969296
03:38:11 134140 8192000000 10090202816 10539761664 1112756720 11.959 97 9 18 1 1185387600 70452624 2178256
03:41:33 40531 8192000000 10106548672 10582847488 1145119616 12.264 100 23 46 0 1240015792 90147648 4748528
03:43:20 71953 8192000000 10083112992 10570825728 1143140768 12.245 100 23 46 0 1200542192 52387744 5013680
03:45:33 32785 8192000000 10120573488 10577694720 1156866976 12.374 100 22 42 1 1225924912 63724128 5333808
03:47:13 91447 8192000000 10115706704 10603294720 1115346912 11.983 97 15 30 0 1206147216 87661216 3139088
03:50:09 67849 8192000000 10068022208 10559471616 1104073776 11.876 97 13 27 0 1159777168 52672560 3030832
03:52:19 28862 8192000000 10076813232 10518704128 1126850832 12.092 99 38 73 0 1166351744 31895040 7605872
03:53:56 55454 8192000000 10043428112 10515439616 1127392048 12.097 99 4 8 0 1149464560 21478576 593936
03:55:52 14552 8192000000 10062478272 10521452544 1134105824 12.160 99 37 72 1 1165040912 23022848 7912240
03:57:08 28442 8192000000 10107179520 10567016448 1137619040 12.193 99 48 90 0 1194690576 47083664 9987872
03:58:36 46410 8192000000 10108270928 10546208768 1098598640 11.824 96 21 40 0 1175993936 72545040 4850256
04:00:28 30686 8192000000 10167165216 10626387968 1139301328 12.209 99 36 71 1 1249520032 103020384 7198320
04:02:04 75411 8192000000 10105767792 10625871872 1130941104 12.130 99 17 34 0 1254722272 119718448 4062720
04:04:53 34183 8192000000 10107150448 10541768704 1128238736 12.105 99 45 90 0 1188591232 51516464 8836032
04:06:30 68285 8192000000 10154776848 10594803712 1138541264 12.202 99 33 62 1 1240568288 95566432 6460592
04:09:19 70427 8192000000 10081695392 10638299136 1138566352 12.202 100 22 44 0 1227403760 84238400 4599008
04:11:34 42196 8192000000 10079307312 10555498496 1131151232 12.132 99 10 20 0 1168385424 35442800 1791392
04:13:16 86411 8192000000 10055309280 10537504768 1096131088 11.801 97 18 33 0 1161337632 61499440 3707104
04:16:06 24848 8192000000 10059513264 10489143296 1113850592 11.969 99 55 88 1 1148732592 25155648 9726352
04:17:34 96595 8192000000 10096748032 10566144000 1081224176 11.659 96 22 44 0 1151671536 66074848 4372512
04:20:15 30532 8192000000 10121387552 10550521856 1119555984 12.023 99 35 70 0 1199162256 72611632 6994640
04:21:48 28315 8192000000 10094794784 10560577536 1104538688 11.881 99 37 74 0 1178036176 65999200 7498288
04:23:19 44192 8192000000 10092678416 10523353088 1127656864 12.099 100 25 49 0 1188316768 55472768 5187136
04:25:07 62995 8192000000 10101280752 10582695936 1107756272 11.911 98 14 26 0 1151430048 41403232 2270544
04:27:11 113750 8192000000 10036950784 10491576320 1121384160 12.040 100 27 54 0 1166683216 40086496 5212560
04:30:13 40329 8192000000 10076936368 10538516480 1141115728 12.226 100 17 30 1 1174711776 30391760 3204288
04:31:53 41135 8192000000 10087938448 10550476800 1130099616 12.122 99 17 34 0 1193465408 59588640 3777152
04:33:39 73099 8192000000 10056484528 10547298304 1128738400 12.109 100 13 26 0 1183007856 51590656 2678800
04:36:00 38440 8192000000 10073335952 10537664512 1133940896 12.158 100 17 32 1 1165051248 27526304 3584048

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-07-2022 04:38:39 to 22-07-2022 04:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

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

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          212736
MON$OLDEST_ACTIVE               214701
MON$OLDEST_SNAPSHOT             214701
MON$NEXT_TRANSACTION            214701
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                       2029600
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2800 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2800 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2800 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2800 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Fri Jul 22 04:37:21 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Fri Jul 22 04:37:22 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
--- end of diff output ---

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

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