Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.04.2022 13: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 16325588 kB
MemAvailable 31527580 kB
Buffers 0 kB
Cached 15416968 kB
SwapCached 2688 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-18 13:37:15
DTS_END                         2022-04-18 13:37:15.3010
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/42578
TRN_ID                          219185
ATT_ID                          649
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8873.71 26205 1064845
customer order: creation 172.62 509 20714
customer order: refuse 14.67 457 1760
order to supplier: creation 45.47 1425 5456
order to supplier: removal 3.40 176124 408
invoice (draft): creation 44.93 3447 5392
invoice (draft): removal 48.08 1093 5770
invoice accept: apply 42.53 31311 5103
invoice accept: cancel 43.27 1484 5192
customer reserve: creation 3943.43 300 473212
customer reserve: removal 4225.57 111 507068
realization accept: apply 82.83 303 9939
realization accept: cancel 78.18 199 9381
payment from customer: creation 58.41 203 7009
payment from customer: removal 5.58 136 669
payment to supplier: creation 48.35 213 5802
payment to supplier: removal 3.40 177 408
service: total inventory turnovers 4.13 4409 495
service: total monetary turnovers 8.03 430 963
service: aggregate perf. data 0.82 18359 98
service: refresh index statistics 0.05 283415 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-04-2022 13:39:28 to 18-04-2022 13:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8120
TEST_TIME 2 6511
TEST_TIME 3 5602
TEST_TIME 4 5404
TEST_TIME 5 5726
TEST_TIME 6 5777
TEST_TIME 7 5675
TEST_TIME 8 5644
TEST_TIME 9 6205
TEST_TIME 10 6279
TEST_TIME 11 6375
TEST_TIME 12 6571
TEST_TIME 13 6622
TEST_TIME 14 6956
TEST_TIME 15 7128
TEST_TIME 16 7223
TEST_TIME 17 7460
TEST_TIME 18 7435
TEST_TIME 19 7492
TEST_TIME 20 7515
TEST_TIME 21 7725
TEST_TIME 22 7914
TEST_TIME 23 8144
TEST_TIME 24 8269
TEST_TIME 25 8431
TEST_TIME 26 8327
TEST_TIME 27 8317
TEST_TIME 28 8586
TEST_TIME 29 8566
TEST_TIME 30 8677
TEST_TIME 31 9305
TEST_TIME 32 9565
TEST_TIME 33 9543
TEST_TIME 34 9873
TEST_TIME 35 10133
TEST_TIME 36 10329
TEST_TIME 37 10540
TEST_TIME 38 10434
TEST_TIME 39 10371
TEST_TIME 40 10328
TEST_TIME 41 10562
TEST_TIME 42 10448
TEST_TIME 43 10238
TEST_TIME 44 10067
TEST_TIME 45 9920
TEST_TIME 46 9809
TEST_TIME 47 9777
TEST_TIME 48 9787
TEST_TIME 49 9685
TEST_TIME 50 9664
TEST_TIME 51 9651
TEST_TIME 52 9568
TEST_TIME 53 9512
TEST_TIME 54 9457
TEST_TIME 55 9373
TEST_TIME 56 9297
TEST_TIME 57 9234
TEST_TIME 58 9210
TEST_TIME 59 9248
TEST_TIME 60 9227
TEST_TIME 61 9362
TEST_TIME 62 9286
TEST_TIME 63 9239
TEST_TIME 64 9239
TEST_TIME 65 9245
TEST_TIME 66 9272
TEST_TIME 67 9326
TEST_TIME 68 9442
TEST_TIME 69 9413
TEST_TIME 70 9375
TEST_TIME 71 9414
TEST_TIME 72 9454
TEST_TIME 73 9397
TEST_TIME 74 9301
TEST_TIME 75 9307
TEST_TIME 76 9253
TEST_TIME 77 9193
TEST_TIME 78 9200
TEST_TIME 79 9139
TEST_TIME 80 9127
TEST_TIME 81 9044
TEST_TIME 82 9035
TEST_TIME 83 8982
TEST_TIME 84 8977
TEST_TIME 85 9025
TEST_TIME 86 9050
TEST_TIME 87 9071
TEST_TIME 88 9080
TEST_TIME 89 9063
TEST_TIME 90 9142
TEST_TIME 91 9143
TEST_TIME 92 9092
TEST_TIME 93 9113
TEST_TIME 94 9156
TEST_TIME 95 9144
TEST_TIME 96 9092
TEST_TIME 97 9076
TEST_TIME 98 9055
TEST_TIME 99 9030
TEST_TIME 100 8973
TEST_TIME 101 8925
TEST_TIME 102 8911
TEST_TIME 103 8883
TEST_TIME 104 8858
TEST_TIME 105 8928
TEST_TIME 106 8899
TEST_TIME 107 8893
TEST_TIME 108 8912
TEST_TIME 109 8877
TEST_TIME 110 8944
TEST_TIME 111 9067
TEST_TIME 112 9027
TEST_TIME 113 9070
TEST_TIME 114 9082
TEST_TIME 115 9069
TEST_TIME 116 9030
TEST_TIME 117 8994
TEST_TIME 118 8951
TEST_TIME 119 8973
TEST_TIME 120 8901

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-04-2022 13:39:32 to 18-04-2022 13:39:35.

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 1499281 1499281 0 0.00 0 18242 17 0 0
doc_list_biud 1499272 1499272 0 0.00 0 29498 44 0 0
sp_add_invoice_to_stock 5212 5103 109 2.09 44 204986 31312 0 109
sp_cancel_adding_invoice 5192 5192 0 0.00 6 81563 1485 0 0
sp_cancel_client_order 1760 1760 0 0.00 6 19846 457 0 0
sp_cancel_customer_reserve 507068 507068 0 0.00 1 28506 111 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 4 2540 136 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 4 17731 178 0 0
sp_cancel_supplier_invoice 5777 5770 7 0.12 3 18356 1093 0 7
sp_cancel_supplier_order 408 408 0 0.00 15 1496393 176124 0 0
sp_cancel_write_off 9381 9381 0 0.00 2 9653 199 0 0
sp_client_order 20714 20714 0 0.00 5 22431 510 0 0
sp_customer_reserve 473212 473212 0 0.00 1 75918 301 0 0
sp_fill_shopping_cart 31553 31553 0 0.00 1 12805 88 0 0
sp_fill_shopping_cart_clo_res 473179 473179 0 0.00 0 18726 20 0 0
sp_get_clo_for_invoice 5065 5065 0 0.00 0 19749 282 0 0
sp_kill_cost_storno 15650 15650 0 0.00 0 18234 89 0 0
sp_kill_qstorno_ret_qs2qd 1029695 1029695 0 0.00 0 28451 48 0 0
sp_kill_qty_storno 544582 544582 0 0.00 0 29495 112 0 0
sp_lock_selected_doc 20717 20717 0 0.00 0 10031 7 0 0
sp_make_cost_storno 27814 27814 0 0.00 0 18224 93 0 0
sp_multiply_rows_for_pdistr 27814 27814 0 0.00 0 8996 44 0 0
sp_multiply_rows_for_qdistr 20714 20714 0 0.00 0 10914 184 0 0
sp_pay_from_customer 7009 7009 0 0.00 6 17802 203 0 0
sp_pay_to_supplier 5802 5802 0 0.00 5 17477 213 0 0
sp_payment_common 12811 12811 0 0.00 2 7503 53 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 1 19836 365 0 0
sp_qd_handle_on_invoice_upd_sts 10256 10256 0 0.00 0 29491 600 0 0
sp_qd_handle_on_reserve_upd_sts 9939 9939 0 0.00 0 10488 59 0 0
sp_reserve_write_off 9939 9939 0 0.00 9 20012 304 0 0
sp_supplier_invoice 5392 5392 0 0.00 12 92589 3448 0 0
sp_supplier_order 5456 5456 0 0.00 7 36645 1426 0 0
srv_aggregate_perf_data 98 98 0 0.00 2261 138337 18359 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 835 163012 30633 0 0
srv_find_qd_qs_mism 1014998 1014998 0 0.00 0 7412 14 0 0
srv_make_invnt_saldo 495 495 0 0.00 45 116597 4410 0 0
srv_make_money_saldo 963 963 0 0.00 1 14847 431 0 0
srv_recalc_idx_stat 6 6 0 0.00 89379 505215 283416 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5111 5111 0 0.00 1 13710 1065 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 2 7061 677 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 2883 31 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2079 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 328 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 640 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1294 37 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 102191 8302 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 3961 23 0 0
t$perf-norm:sp_client_order 20714 20714 0 0.00 0 7767 42 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 882 16 0 0
t$perf-norm:sp_pay_from_customer 7009 7009 0 0.00 0 6977 23 0 0
t$perf-norm:sp_pay_to_supplier 5802 5802 0 0.00 0 5176 21 0 0
t$perf-norm:sp_reserve_write_off 9939 9939 0 0.00 0 14740 26 0 0
t$perf-norm:sp_supplier_invoice 5392 5392 0 0.00 0 3638 35 0 0
t$perf-norm:sp_supplier_order 5456 5456 0 0.00 0 2187 36 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 390 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 1641 29 0 0
t$perf-norm:srv_make_invnt_saldo 495 495 0 0.00 0 721 18 0 0
t$perf-norm:srv_make_money_saldo 963 963 0 0.00 0 809 8 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 174 103 0 0
v_add_invoice_to_stock 10338 10120 218 2.10 0 15927 26 0 218
v_all_customers 38124 38124 0 0.00 0 67 4 0 0
v_all_suppliers 30450 30450 0 0.00 0 104 5 0 0
v_all_wares 41426 41426 0 0.00 0 85 4 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 363 15 0 0
v_cancel_client_order 3520 3520 0 0.00 0 17213 17 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 1796 14 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 633 11 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 17648 44 0 0
v_cancel_supplier_invoice 820 806 14 1.70 0 4273 32 0 14
v_cancel_supplier_order 816 816 0 0.00 0 2799 18 0 0
v_cancel_write_off 1988 1988 0 0.00 0 457 23 0 0
v_max_id_clo_ord 5451 5451 0 0.00 0 134 5 0 0
v_max_id_clo_res 975 975 0 0.00 0 1546 12 0 0
v_max_id_ord_sup 5386 5386 0 0.00 0 4775 7 0 0
v_max_non_paid_invoice 1413 1413 0 0.00 0 731 9 0 0
v_max_non_paid_realizn 3542 3542 0 0.00 0 3105 9 0 0
v_min_id_clo_ord 5451 5451 0 0.00 0 5034 9 0 0
v_min_id_clo_res 975 975 0 0.00 0 4341 14 0 0
v_min_id_ord_sup 5386 5386 0 0.00 0 7397 12 0 0
v_min_non_paid_invoice 5802 5802 0 0.00 0 6228 18 0 0
v_min_non_paid_realizn 7009 7009 0 0.00 0 7494 26 0 0
v_our_firm 10236 10236 0 0.00 0 74 5 0 0
v_reserve_write_off 19878 19878 0 0.00 0 14690 12 0 0
x_lock_depdocs_on_canc_invoice 10559 10559 0 0.00 0 18484 136 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 21226 421 0 0
x_make_qstorno_1000_1200 5456 5456 0 0.00 3 36618 1245 0 0
x_make_qstorno_1200_2000 5392 5392 0 0.00 6 92520 3211 0 0
x_make_qstorno_2100_3300 473211 473211 0 0.00 0 75915 267 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-04-2022 13:39:36 to 18-04-2022 13:39:40.

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
11:07:19 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:20 20508 8192000000 9727314704 10027237376 806168048 8.959 100 84 168 0 834101968 7423056 20510864
11:09:55 22836 8192000000 9756035664 10066288640 832132000 9.221 100 84 168 0 865516544 13171040 20213504
11:11:24 18127 8192000000 9808247808 10127339520 871501392 9.615 100 85 168 0 914384864 23760496 19122976
11:12:46 11924 8192000000 9867636128 10203865088 911881616 10.016 100 82 164 0 979498800 49319344 18297840
11:14:01 9784 8192000000 9921874400 10265305088 951253552 10.403 100 88 174 1 1032491584 63126544 18111488
11:15:15 2651 8192000000 9964483872 10315063296 981833136 10.702 100 89 175 1 1078657904 79283856 17540912
11:16:20 2560 8192000000 9974145264 10335698944 1006874464 10.945 100 90 169 0 1101195696 77682704 16638528
11:17:25 1105 8192000000 10001955472 10370035712 1036880928 11.235 100 94 178 1 1121317424 66385616 18050880
11:18:28 1641 8192000000 9997763696 10377457664 1052513696 11.385 100 92 168 0 1120379408 50739792 17125920
11:19:32 915 8192000000 10056734512 10438492160 1062351120 11.479 100 94 177 0 1178320944 98778720 17191104
11:20:35 4509 8192000000 10020899392 10399576064 1069056832 11.543 100 78 140 3 1150904976 68459424 13388720
11:21:42 1012 8192000000 10047414592 10432536576 1077921824 11.628 100 99 196 1 1172043536 74272864 19848848
11:22:44 1148 8192000000 10053453792 10439319552 1081575920 11.662 100 98 190 1 1173863008 73136032 19151056
11:23:46 1315 8192000000 10065082384 10450726912 1088513616 11.729 100 95 185 0 1185888160 78871024 18503520
11:24:49 1024 8192000000 10069639888 10462707712 1098323440 11.822 100 93 181 1 1196511728 78007296 20180992
11:25:52 1760 8192000000 10065368096 10462924800 1097291408 11.812 100 75 150 2 1191888080 80198576 14398096
11:26:55 1364 8192000000 10056113024 10447831040 1097402384 11.813 100 95 181 0 1171794928 55516080 18876464
11:27:57 1183 8192000000 10059690816 10446954496 1098016848 11.819 100 95 173 1 1178155008 61816704 18321456
11:28:59 1410 8192000000 10085655728 10475356160 1096250496 11.802 99 94 181 1 1203984208 89779888 17953824
11:30:02 1124 8192000000 10075824256 10465742848 1101659408 11.853 100 94 185 2 1195361952 74858384 18844160
11:31:04 1027 8192000000 10055825936 10442620928 1088186032 11.725 100 97 182 1 1179786384 72300144 19300208
11:32:06 1655 8192000000 10057818528 10440163328 1079272976 11.641 100 94 183 3 1180811152 81935152 19603024
11:33:11 1185 8192000000 10009200048 10384850944 1044219392 11.305 100 94 184 0 1133600384 70962624 18418368
11:34:13 1132 8192000000 9999482544 10375618560 1037029728 11.236 100 86 165 1 1124621472 70545584 17046160
11:35:16 5365 8192000000 9979254992 10364997632 1028500384 11.154 99 62 119 1 1108756016 67603920 12651712
11:36:23 6836 8192000000 9988003792 10364993536 1037902864 11.245 100 70 132 1 1103775904 53586272 12286768
11:37:37 12790 8192000000 9991371984 10372214784 1057847232 11.436 100 66 130 0 1115639824 45017008 12775584
11:38:56 15959 8192000000 10031810032 10412036096 1068952848 11.542 100 69 135 0 1151978992 69670256 13355888
11:40:20 5560 8192000000 10045410608 10426769408 1069696576 11.549 99 80 147 2 1162834288 78574736 14562976
11:41:36 3743 8192000000 10084396960 10461126656 1091282848 11.755 100 85 161 1 1197554048 89679280 16591920
11:42:43 3270 8192000000 10114066800 10493997056 1084751616 11.693 99 85 166 0 1228249152 126091856 17405680
11:43:59 2929 8192000000 10139388480 10535501824 1109284640 11.926 100 63 118 3 1260465632 137723296 13457696
11:45:12 1113 8192000000 10165639936 10564476928 1120701968 12.034 100 93 178 4 1282527424 142175008 19650448
11:46:18 1367 8192000000 10205260800 10604490752 1140120528 12.217 100 96 180 1 1320736160 161011408 19604224
11:47:24 4304 8192000000 10236796272 10632822784 1150206960 12.311 100 79 155 0 1349325472 180867472 18251040
11:48:32 1191 8192000000 10250365104 10650705920 1143356336 12.247 99 82 164 2 1370621312 207661632 19603344
11:49:37 967 8192000000 10295403824 10703110144 1164705184 12.447 100 98 191 0 1405363776 219832944 20825648
11:50:43 1321 8192000000 10301760112 10712104960 1162041744 12.422 100 87 170 1 1410876416 230091616 18743056
11:51:51 1145 8192000000 10324695792 10745098240 1164541392 12.446 100 93 178 0 1432749936 249253296 18955248
11:52:56 1585 8192000000 10352411312 10769977344 1174752784 12.541 100 96 181 0 1461754288 266496208 20505296
11:54:01 1331 8192000000 10377534800 10803449856 1169290624 12.490 100 100 194 0 1483217760 292662528 21264608
11:55:08 829 8192000000 10390516000 10796453888 1186941440 12.655 100 95 184 1 1494624384 287295552 20387392
11:56:13 1354 8192000000 10382104160 10793103360 1171936128 12.515 100 89 174 0 1490272032 297821392 20514512
11:57:21 881 8192000000 10428905104 10844680192 1188897136 12.673 100 94 187 0 1533417600 321877376 22643088
11:58:26 1058 8192000000 10419615040 10857611264 1181665968 12.606 100 92 180 1 1524005488 320766320 21573200
11:59:30 1061 8192000000 10432316016 10871443456 1170080736 12.498 100 96 186 1 1537607360 345720720 21805904
12:00:35 836 8192000000 10461453536 10879840256 1179407536 12.585 100 94 179 0 1564191552 365008624 19775392
12:01:42 5515 8192000000 10420672448 10861338624 1185322464 12.640 100 56 111 2 1529676032 330644544 13709024
12:02:50 10809 8192000000 10429467216 10865745920 1168342832 12.481 100 53 101 0 1533878176 353615600 11919744
12:04:05 1326 8192000000 10488385904 10903740416 1166101392 12.460 99 95 189 0 1592473968 404122368 22250208
12:05:11 862 8192000000 10467970768 10926194688 1165312096 12.453 100 98 194 2 1573237840 384697328 23228416
12:06:17 1053 8192000000 10502960416 10946912256 1180429344 12.594 100 75 148 1 1610850944 411975120 18446480
12:07:21 2073 8192000000 10497673216 11001552896 1183127616 12.619 100 83 152 2 1595957312 394790544 18039152
12:08:26 1147 8192000000 10448550256 10933989376 1182560048 12.614 100 64 127 2 1551593360 353240160 15793152
12:09:32 22358 8192000000 10369313488 10833993728 1166004688 12.459 99 38 71 0 1457613184 281923200 9685296
12:11:14 27140 8192000000 10301279936 10830675968 1158540816 12.390 100 18 36 1 1402735072 239340352 4853904
12:13:02 29297 8192000000 10181129312 10669821952 1121104480 12.037 98 19 38 1 1284462880 157829808 5528592
12:14:56 31219 8192000000 10109068672 10519498752 1124340368 12.068 98 52 93 1 1189664848 56790608 8533872
12:16:29 23975 8192000000 10137858608 10571714560 1136972608 12.187 99 53 104 5 1229289104 81470352 10846144
12:18:05 31210 8192000000 10120615024 10590777344 1136948240 12.187 100 17 35 1 1242957296 102261360 3747696
12:19:45 65850 8192000000 10055182928 10493272064 1116897264 11.998 99 24 47 2 1141381232 19919168 4564800
12:22:02 6291 8192000000 10080254176 10509660160 1130846016 12.129 100 68 131 1 1171495888 27322912 13326960
12:23:15 22022 8192000000 10075214864 10514718720 1139497248 12.211 100 35 65 0 1181583216 35890896 6195072
12:24:44 23849 8192000000 10105048256 10545934336 1124138192 12.066 99 46 92 0 1196189632 63038160 9013280
12:26:12 3879 8192000000 10065462944 10486874112 1127282464 12.096 100 87 159 1 1159678896 14834960 17561472
12:27:22 14923 8192000000 10082834768 10535223296 1120685040 12.033 100 31 60 1 1182271200 55404928 6181232
12:28:38 54972 8192000000 10072609728 10517528576 1120954048 12.036 99 19 38 0 1159139808 33978928 4206832
12:30:59 74579 8192000000 10073824048 10508181504 1098121824 11.820 97 20 40 1 1138280016 35681088 4477104
12:33:17 89106 8192000000 10127779712 10596020224 1097916560 11.818 97 22 44 1 1215023856 112127440 4979856
12:35:54 59191 8192000000 10087036528 10573533184 1099728112 11.835 98 24 47 1 1218408816 112752768 5927936
12:37:57 84595 8192000000 10053052688 10576891904 1117797936 12.006 100 11 22 1 1182316016 62116128 2401952
12:40:22 54820 8192000000 10093726864 10543833088 1094047344 11.781 97 34 68 0 1171136224 69838128 7250752
12:42:22 37310 8192000000 10132464944 10576048128 1118457408 12.012 99 37 74 0 1221511872 94141808 8912656
12:44:01 30904 8192000000 10168142208 10613325824 1125692864 12.081 99 51 100 2 1241328464 103821088 11814512
12:45:32 40099 8192000000 10075133600 10504773632 1119543184 12.023 99 31 62 0 1177246096 50799296 6903616
12:47:13 11521 8192000000 10080334640 10503213056 1097517136 11.814 98 45 87 1 1155008096 49077904 8413056
12:48:26 22770 8192000000 10096769328 10535964672 1106326064 11.898 99 36 72 0 1183124944 69596752 7202128
12:49:50 38242 8192000000 10083274592 10510176256 1101768800 11.854 98 29 58 1 1153038256 45690384 5579072
12:51:29 90599 8192000000 10050625280 10495844352 1107877856 11.912 98 35 70 0 1184286032 68335456 8072720
12:54:02 12802 8192000000 10065952720 10491637760 1130797984 12.129 100 51 98 1 1170186720 28781584 10607152
12:55:17 32457 8192000000 10091943008 10556854272 1120025040 12.027 99 35 70 0 1202497568 75807104 6665424
12:58:34 86653 8192000000 10070953408 10552619008 1087416720 11.718 97 14 28 1 1170425920 79826048 3183152
13:01:00 92144 8192000000 10084954240 10565566464 1103326704 11.869 98 23 46 0 1182459392 73660800 5471888
13:03:37 29755 8192000000 10076717248 10510823424 1104198656 11.877 99 37 73 0 1156548464 45182400 7167408
13:05:12 38710 8192000000 10077994368 10523856896 1077690656 11.625 96 26 52 0 1139574448 56765440 5118352
13:06:55 42574 8192000000 10096528352 10571014144 1093983024 11.781 98 45 90 0 1200245312 97390064 8872224
13:08:42 42662 8192000000 10059505888 10498101248 1092736720 11.769 98 36 72 1 1149083008 49193968 7152320
13:10:25 20582 8192000000 10154827568 10593169408 1123362240 12.059 100 57 114 0 1254867840 120536912 10968688
13:11:58 163012 8192000000 10076448064 10545684480 1127881808 12.101 100 27 54 0 1202975936 69569968 5524160
13:15:52 55925 8192000000 10084402800 10557308928 1104898480 11.884 98 23 46 0 1181896448 72535184 4462784
13:18:09 76022 8192000000 10109485120 10610323456 1131199008 12.133 99 15 30 1 1221580656 86989008 3392640
13:20:26 80312 8192000000 10146831440 10594799616 1105498128 11.890 97 22 45 0 1205594656 95466256 4630272
13:22:49 48810 8192000000 10133921088 10574442496 1105398336 11.889 96 23 45 1 1183416992 73471088 4547568
13:24:43 40777 8192000000 10168013584 10627731456 1133061792 12.150 99 47 92 1 1260586544 117574192 9950560
13:26:29 35696 8192000000 10188334576 10642468864 1132745984 12.147 99 46 92 0 1295382048 152455440 10180624
13:28:26 52773 8192000000 10083542768 10538479616 1127910688 12.102 100 33 66 0 1195457504 60239392 7307424
13:30:20 30066 8192000000 10096100272 10560454656 1093609888 11.777 97 40 80 1 1162698848 61144176 7944784
13:31:55 66401 8192000000 10092351328 10523377664 1104605776 11.881 98 38 76 0 1160343392 48489792 7247824
13:34:02 117576 8192000000 10116757296 10572439552 1096450368 11.804 97 40 80 0 1178604880 73801552 8352960
13:37:01 22748 8192000000 9334523824 9692459008 454375088 5.255 41 1 2 0 474332128 19921632 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 18-04-2022 13:39:42 to 18-04-2022 13:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-04-2022 13:40:00 to 18-04-2022 13:40:02.

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          219538
MON$OLDEST_ACTIVE               219539
MON$OLDEST_SNAPSHOT             219539
MON$NEXT_TRANSACTION            219539
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                       2066272
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             773
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 ---
:300: 
:301: mscs-test-161.budzdorov.ru    Mon Apr 18 13:37:28 2022
:302:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:303: 
:304: 
:305: mscs-test-161.budzdorov.ru    Mon Apr 18 13:37:34 2022
:306:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:307: 
:308: 
:309: mscs-test-161.budzdorov.ru    Mon Apr 18 13:37:34 2022
:310:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:311: 
--- end of diff output ---

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

18.04.22 13:40:03. 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-18 13:40:05 No severe PSQL-related problems occured
18.04.22 13:40:06. End of report.