Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.05.2022 10: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 16242592 kB
MemAvailable 31491076 kB
Buffers 0 kB
Cached 15495256 kB
SwapCached 2656 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-05-14 10:37:17
DTS_END                         2022-05-14 10:37:17.6110
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/39056
TRN_ID                          213213
ATT_ID                          608
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: 9133.90 27078 1096068
customer order: creation 163.01 502 19561
customer order: refuse 13.88 446 1665
order to supplier: creation 43.13 1469 5175
order to supplier: removal 3.48 172206 417
invoice (draft): creation 43.57 3382 5228
invoice (draft): removal 54.96 995 6595
invoice accept: apply 39.08 25112 4690
invoice accept: cancel 49.52 1103 5942
customer reserve: creation 3579.50 242 429540
customer reserve: removal 4863.41 98 583609
realization accept: apply 77.65 300 9318
realization accept: cancel 82.67 173 9920
payment from customer: creation 55.70 186 6684
payment from customer: removal 5.07 117 608
payment to supplier: creation 44.94 207 5393
payment to supplier: removal 3.15 153 378
service: total inventory turnovers 3.33 4724 399
service: total monetary turnovers 7.01 367 841
service: aggregate perf. data 0.84 17507 101
service: refresh index statistics 0.03 312276 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-05-2022 10:39:03 to 14-05-2022 10:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8212
TEST_TIME 2 7188
TEST_TIME 3 6702
TEST_TIME 4 6720
TEST_TIME 5 6613
TEST_TIME 6 6334
TEST_TIME 7 6564
TEST_TIME 8 6470
TEST_TIME 9 6794
TEST_TIME 10 7096
TEST_TIME 11 7048
TEST_TIME 12 7264
TEST_TIME 13 7553
TEST_TIME 14 7785
TEST_TIME 15 7856
TEST_TIME 16 7873
TEST_TIME 17 8054
TEST_TIME 18 8305
TEST_TIME 19 8351
TEST_TIME 20 8394
TEST_TIME 21 8242
TEST_TIME 22 8037
TEST_TIME 23 8225
TEST_TIME 24 8422
TEST_TIME 25 8360
TEST_TIME 26 8702
TEST_TIME 27 8981
TEST_TIME 28 9262
TEST_TIME 29 9572
TEST_TIME 30 9857
TEST_TIME 31 10234
TEST_TIME 32 10471
TEST_TIME 33 10985
TEST_TIME 34 11007
TEST_TIME 35 11046
TEST_TIME 36 11108
TEST_TIME 37 10964
TEST_TIME 38 10853
TEST_TIME 39 10651
TEST_TIME 40 10635
TEST_TIME 41 10493
TEST_TIME 42 10349
TEST_TIME 43 10254
TEST_TIME 44 10189
TEST_TIME 45 10354
TEST_TIME 46 10221
TEST_TIME 47 10106
TEST_TIME 48 10146
TEST_TIME 49 10013
TEST_TIME 50 9876
TEST_TIME 51 9893
TEST_TIME 52 9861
TEST_TIME 53 9989
TEST_TIME 54 10011
TEST_TIME 55 10008
TEST_TIME 56 9963
TEST_TIME 57 9915
TEST_TIME 58 9881
TEST_TIME 59 9950
TEST_TIME 60 9895
TEST_TIME 61 9998
TEST_TIME 62 10082
TEST_TIME 63 10028
TEST_TIME 64 9893
TEST_TIME 65 9813
TEST_TIME 66 9753
TEST_TIME 67 9701
TEST_TIME 68 9641
TEST_TIME 69 9556
TEST_TIME 70 9551
TEST_TIME 71 9509
TEST_TIME 72 9476
TEST_TIME 73 9509
TEST_TIME 74 9528
TEST_TIME 75 9536
TEST_TIME 76 9513
TEST_TIME 77 9453
TEST_TIME 78 9722
TEST_TIME 79 9803
TEST_TIME 80 9765
TEST_TIME 81 9845
TEST_TIME 82 9770
TEST_TIME 83 9660
TEST_TIME 84 9640
TEST_TIME 85 9570
TEST_TIME 86 9579
TEST_TIME 87 9500
TEST_TIME 88 9447
TEST_TIME 89 9464
TEST_TIME 90 9396
TEST_TIME 91 9324
TEST_TIME 92 9315
TEST_TIME 93 9333
TEST_TIME 94 9319
TEST_TIME 95 9274
TEST_TIME 96 9256
TEST_TIME 97 9238
TEST_TIME 98 9223
TEST_TIME 99 9242
TEST_TIME 100 9199
TEST_TIME 101 9231
TEST_TIME 102 9189
TEST_TIME 103 9348
TEST_TIME 104 9379
TEST_TIME 105 9428
TEST_TIME 106 9444
TEST_TIME 107 9496
TEST_TIME 108 9466
TEST_TIME 109 9425
TEST_TIME 110 9368
TEST_TIME 111 9331
TEST_TIME 112 9328
TEST_TIME 113 9299
TEST_TIME 114 9268
TEST_TIME 115 9217
TEST_TIME 116 9178
TEST_TIME 117 9180
TEST_TIME 118 9175
TEST_TIME 119 9192
TEST_TIME 120 9153

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-05-2022 10:39:06 to 14-05-2022 10:39:10.

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 1494724 1494724 0 0.00 0 50430 13 0 0
doc_list_biud 1494705 1494705 0 0.00 0 64815 44 0 0
sp_add_invoice_to_stock 4786 4690 96 2.00 38 198490 25112 0 96
sp_cancel_adding_invoice 5942 5942 0 0.00 7 78849 1104 0 0
sp_cancel_client_order 1665 1665 0 0.00 6 14968 447 0 0
sp_cancel_customer_reserve 583609 583609 0 0.00 1 64817 99 0 0
sp_cancel_pay_from_customer 608 608 0 0.00 4 2042 118 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 4 8529 153 0 0
sp_cancel_supplier_invoice 6607 6595 12 0.18 2 29431 995 0 12
sp_cancel_supplier_order 417 417 0 0.00 9 1264231 172207 0 0
sp_cancel_write_off 9920 9920 0 0.00 2 17631 173 0 0
sp_client_order 19561 19561 0 0.00 5 34531 502 0 0
sp_customer_reserve 429540 429540 0 0.00 1 149505 242 0 0
sp_fill_shopping_cart 29954 29954 0 0.00 2 13817 81 0 0
sp_fill_shopping_cart_clo_res 429513 429513 0 0.00 0 13413 19 0 0
sp_get_clo_for_invoice 4653 4653 0 0.00 0 30984 259 0 0
sp_kill_cost_storno 16848 16848 0 0.00 0 50414 71 0 0
sp_kill_qstorno_ret_qs2qd 1184150 1184150 0 0.00 0 64698 44 0 0
sp_kill_qty_storno 622116 622116 0 0.00 0 64814 100 0 0
sp_lock_selected_doc 19377 19377 0 0.00 0 7103 7 0 0
sp_make_cost_storno 26046 26046 0 0.00 0 49964 81 0 0
sp_multiply_rows_for_pdistr 26044 26044 0 0.00 0 9051 41 0 0
sp_multiply_rows_for_qdistr 19561 19561 0 0.00 0 7957 187 0 0
sp_pay_from_customer 6684 6684 0 0.00 6 27457 187 0 0
sp_pay_to_supplier 5393 5393 0 0.00 6 31199 208 0 0
sp_payment_common 12076 12076 0 0.00 2 5707 45 0 0
sp_qd_handle_on_cancel_clo 1665 1665 0 0.00 1 11004 366 0 0
sp_qd_handle_on_invoice_upd_sts 10593 10593 0 0.00 0 36238 497 0 0
sp_qd_handle_on_reserve_upd_sts 9317 9317 0 0.00 0 43222 76 0 0
sp_reserve_write_off 9318 9318 0 0.00 9 43528 301 0 0
sp_supplier_invoice 5228 5228 0 0.00 11 103469 3383 0 0
sp_supplier_order 5175 5175 0 0.00 8 33187 1469 0 0
srv_aggregate_perf_data 101 101 0 0.00 2750 238776 17507 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 623 156915 38083 0 0
srv_find_qd_qs_mism 1051903 1051903 0 0.00 0 33079 9 0 0
srv_make_invnt_saldo 399 399 0 0.00 127 68686 4724 0 0
srv_make_money_saldo 841 841 0 0.00 2 9995 368 0 0
srv_recalc_idx_stat 4 4 0 0.00 88368 446816 312277 0 0
t$perf-abend:sp_add_invoice_to_stock 96 96 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4692 4692 0 0.00 1 14272 926 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 2 6778 451 0 0
t$perf-norm:sp_cancel_client_order 1665 1665 0 0.00 0 6308 34 0 0
t$perf-norm:sp_cancel_customer_reserve 638 638 0 0.00 0 2948 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 608 608 0 0.00 0 741 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 450 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 3808 38 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 85302 5917 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 1645 19 0 0
t$perf-norm:sp_client_order 19561 19561 0 0.00 0 5005 35 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 2231 14 0 0
t$perf-norm:sp_pay_from_customer 6684 6684 0 0.00 0 4244 17 0 0
t$perf-norm:sp_pay_to_supplier 5393 5393 0 0.00 0 4987 20 0 0
t$perf-norm:sp_reserve_write_off 9319 9319 0 0.00 0 9485 24 0 0
t$perf-norm:sp_supplier_invoice 5228 5228 0 0.00 0 10852 35 0 0
t$perf-norm:sp_supplier_order 5175 5175 0 0.00 0 7435 38 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 218 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 44 3 0 0
t$perf-norm:srv_make_invnt_saldo 399 399 0 0.00 0 2595 20 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 978 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 177 68 0 0
v_add_invoice_to_stock 9487 9295 192 2.02 0 17901 29 0 192
v_all_customers 36082 36082 0 0.00 0 70 4 0 0
v_all_suppliers 28930 28930 0 0.00 0 63 4 0 0
v_all_wares 39120 39120 0 0.00 0 118 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 920 14 0 0
v_cancel_client_order 3330 3330 0 0.00 0 14581 17 0 0
v_cancel_customer_prepayment 1216 1216 0 0.00 0 1961 13 0 0
v_cancel_customer_reserve 1276 1276 0 0.00 0 12687 22 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 8489 23 0 0
v_cancel_supplier_invoice 796 772 24 3.01 0 27546 117 0 24
v_cancel_supplier_order 834 834 0 0.00 0 3417 15 0 0
v_cancel_write_off 1830 1830 0 0.00 0 17146 33 0 0
v_max_id_clo_ord 5174 5174 0 0.00 0 2093 6 0 0
v_max_id_clo_res 959 959 0 0.00 0 2209 18 0 0
v_max_id_ord_sup 5220 5220 0 0.00 0 4475 8 0 0
v_max_non_paid_invoice 1322 1322 0 0.00 0 167 8 0 0
v_max_non_paid_realizn 3373 3373 0 0.00 0 3198 8 0 0
v_min_id_clo_ord 5174 5174 0 0.00 0 2915 10 0 0
v_min_id_clo_res 959 959 0 0.00 0 931 8 0 0
v_min_id_ord_sup 5220 5220 0 0.00 0 4720 12 0 0
v_min_non_paid_invoice 5393 5393 0 0.00 0 5700 16 0 0
v_min_non_paid_realizn 6682 6682 0 0.00 0 4719 17 0 0
v_our_firm 9658 9658 0 0.00 0 75 5 0 0
v_reserve_write_off 18634 18634 0 0.00 0 27239 17 0 0
x_lock_depdocs_on_canc_invoice 12151 12151 0 0.00 0 18112 128 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 8222 382 0 0
x_make_qstorno_1000_1200 5175 5175 0 0.00 3 33180 1298 0 0
x_make_qstorno_1200_2000 5228 5228 0 0.00 4 103434 3176 0 0
x_make_qstorno_2100_3300 429539 429539 0 0.00 0 149503 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-05-2022 10:39:11 to 14-05-2022 10:39:15.

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
08:07:20 5 8192000000 8798447392 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
08:08:21 31498 8192000000 9737289696 10038919168 804238128 8.939 100 85 170 0 833231760 7714144 21279488
08:09:55 28998 8192000000 9775264416 10088316928 849534208 9.395 100 88 176 0 883295440 13047248 20713984
08:11:35 18339 8192000000 9834528624 10162364416 897016304 9.869 100 88 174 2 942647264 25948368 19682592
08:13:00 7887 8192000000 9874742816 10212884480 924311424 10.139 100 81 159 1 986773504 45558352 16903728
08:14:14 5746 8192000000 9934760256 10282536960 959087056 10.480 100 87 173 1 1057793872 80920976 17785840
08:15:24 3335 8192000000 9978772112 10334040064 992208096 10.803 100 93 183 0 1106227904 95863424 18156384
08:16:30 1263 8192000000 9978886800 10342772736 1025677136 11.127 100 93 178 0 1101190272 57814464 17698672
08:17:33 965 8192000000 10016858976 10390065152 1046135376 11.324 100 97 191 0 1130471664 65897728 18438560
08:18:35 936 8192000000 10034303200 10408583168 1058313328 11.440 100 96 189 1 1152969488 75689024 18967136
08:19:37 1312 8192000000 10053895920 10432499712 1072594384 11.577 100 95 182 0 1173516528 82625648 18296496
08:20:40 1044 8192000000 10065074624 10448424960 1080497200 11.652 100 96 191 0 1186398960 87210544 18691216
08:21:42 888 8192000000 10049552480 10437824512 1087430576 11.718 100 95 176 2 1168921776 63276352 18214848
08:22:44 879 8192000000 10054706240 10444873728 1090532224 11.748 100 84 163 0 1174421248 65904272 17984752
08:23:46 1132 8192000000 10069060880 10456903680 1100735744 11.845 100 93 174 2 1187130688 68319504 18075440
08:24:48 1205 8192000000 10109888208 10504769536 1103812992 11.874 100 95 187 0 1226994016 103445776 19735248
08:25:50 1224 8192000000 10090459344 10482716672 1111880752 11.950 100 96 187 0 1207707504 76918080 18908672
08:26:52 1187 8192000000 10062451376 10454155264 1111453264 11.946 100 94 182 2 1182099632 51493520 19152848
08:27:54 1192 8192000000 10056687856 10442543104 1096856128 11.808 99 93 179 1 1176419232 61765056 17798048
08:28:56 1089 8192000000 10053147536 10444951552 1103749360 11.873 100 88 160 1 1173889328 53958112 16181856
08:29:59 5146 8192000000 10036045584 10434088960 1085586800 11.701 100 78 149 0 1166411936 65405392 15419744
08:31:05 4675 8192000000 10028655136 10423668736 1070749808 11.559 100 68 136 0 1150264416 65870256 13644352
08:32:11 4476 8192000000 10004512384 10381651968 1046910000 11.331 97 70 131 3 1117751280 58042880 12798400
08:33:17 1025 8192000000 9971571728 10340745216 1035230336 11.219 100 91 172 1 1089891552 37464880 17196336
08:34:19 1544 8192000000 9978817376 10351620096 1020790864 11.080 99 88 172 0 1094289056 56214048 17284144
08:35:30 1427 8192000000 10001009744 10376691712 1045346288 11.316 100 93 178 2 1123038992 59347456 18345248
08:36:32 1168 8192000000 9981574384 10356404224 1032701728 11.194 100 91 175 2 1103234832 50605232 19927872
08:37:37 1229 8192000000 10009070256 10381225984 1049049584 11.352 100 89 169 1 1128225920 63132320 16044016
08:38:47 19661 8192000000 10045180496 10428583936 1074443952 11.594 100 69 138 0 1167034064 77899472 14690640
08:40:23 1210 8192000000 10048028848 10430279680 1091953408 11.761 100 82 159 1 1166800752 58314464 16532880
08:41:31 1021 8192000000 10090418336 10474577920 1112993328 11.961 100 93 169 0 1210719392 79674640 18051424
08:42:35 962 8192000000 10121679952 10507894784 1118171504 12.010 100 96 182 0 1241326704 103281488 19873712
08:43:40 6832 8192000000 10163325888 10559463424 1126854352 12.092 100 78 147 1 1276783360 135050576 14878432
08:44:52 3726 8192000000 10174518176 10571919360 1138334160 12.200 100 71 137 1 1292042656 139275120 14433376
08:46:00 1671 8192000000 10231816816 10626502656 1145644928 12.269 100 92 174 0 1335962912 171935472 18382512
08:47:04 1831 8192000000 10206165120 10605395968 1143781808 12.251 100 85 163 0 1320512560 159391872 17338880
08:48:08 4252 8192000000 10225948032 10628997120 1147058176 12.282 100 79 146 4 1337796160 174161936 16576048
08:49:21 2305 8192000000 10235622672 10648326144 1160827408 12.411 100 93 179 0 1345015024 163916240 20271376
08:50:27 1091 8192000000 10267982912 10668429312 1176043520 12.553 100 96 186 0 1381064912 184163600 20857792
08:51:30 620 8192000000 10286754256 10698219520 1179242576 12.583 100 95 188 0 1397618464 198085328 20290560
08:52:33 1011 8192000000 10332859808 10733936640 1181295376 12.602 100 92 177 0 1437187360 235875008 20016976
08:53:38 785 8192000000 10326063104 10742247424 1169794848 12.495 100 94 187 3 1430385648 240004032 20586768
08:54:41 1502 8192000000 10331359520 10764783616 1176297456 12.556 100 93 181 2 1441299520 243856048 21146016
08:55:45 1180 8192000000 10373961344 10791059456 1181482624 12.604 100 93 178 0 1475183296 272387632 21313040
08:56:48 1217 8192000000 10388257472 10807099392 1180714528 12.597 100 96 184 0 1496514832 295668880 20131424
08:57:52 2903 8192000000 10435215504 10845020160 1183732480 12.625 100 72 142 0 1543982976 342631408 17619088
08:58:58 1671 8192000000 10485291632 10894663680 1193435216 12.715 100 79 153 0 1587808496 375725104 18648176
09:00:06 2107 8192000000 10527870464 10984087552 1199203248 12.769 100 76 150 1 1634951424 416473248 19274928
09:01:15 9902 8192000000 10528280208 10992611328 1190655680 12.689 100 55 110 0 1634645024 429165024 14824320
09:02:27 13488 8192000000 10546940560 10992594944 1162229056 12.424 99 51 102 0 1641187120 465168528 13789536
09:04:16 7260 8192000000 10471030080 10948620288 1150592400 12.315 99 38 76 1 1576166016 415333584 10240032
09:05:27 15103 8192000000 10421445920 10897805312 1143001904 12.244 100 43 86 0 1550118352 396366064 10750384
09:06:50 15652 8192000000 10347366784 10823872512 1140953568 12.224 100 37 74 1 1478275824 327903664 9418592
09:08:06 31533 8192000000 10266915296 10764738560 1144597408 12.259 100 23 46 0 1426338656 276547952 5193296
09:09:44 37229 8192000000 10134955056 10656464896 1105403440 11.889 98 20 40 0 1244181456 134178368 4599648
09:11:22 38860 8192000000 10127641168 10596843520 1109565744 11.928 98 19 38 0 1209129760 95075152 4488864
09:13:01 65623 8192000000 10087580432 10540507136 1123933664 12.064 99 17 30 2 1188510176 61411920 3164592
09:15:17 125836 8192000000 10056835760 10544078848 1109488736 11.928 97 27 49 0 1179704000 64826480 5388784
09:19:06 14708 8192000000 10071003408 10504626176 1118121184 12.009 99 44 81 0 1175507728 48554016 8832528
09:20:23 51336 8192000000 10175932608 10627739648 1136949584 12.187 100 59 111 2 1274293104 124708608 12634912
09:22:27 34440 8192000000 10086460624 10599272448 1134658720 12.165 100 12 24 0 1204085568 66841328 2585520
09:24:15 27408 8192000000 10108394320 10566385664 1124155632 12.066 99 10 20 1 1206621744 80215808 2250304
09:25:54 130841 8192000000 10179523744 10650042368 1142355312 12.238 100 14 28 1 1264105280 118626880 3123088
09:29:12 47674 8192000000 10137973184 10617466880 1135851808 12.176 100 17 32 1 1265168064 125230992 4085264
09:31:01 61518 8192000000 10077826768 10546991104 1084495520 11.690 96 23 46 1 1156487872 67104064 4888288
09:33:46 39592 8192000000 10101625504 10556792832 1120708064 12.034 99 29 58 1 1202412384 75835264 5869056
09:35:30 24848 8192000000 10127524416 10577297408 1118705792 12.015 98 56 110 1 1220838992 90444576 11688624
09:36:58 22361 8192000000 10147236576 10580189184 1139937696 12.215 100 37 74 1 1216962384 69389616 7635072
09:38:21 70081 8192000000 10068053312 10582630400 1104890896 11.884 96 19 36 2 1153153616 44693632 3569088
09:40:41 81754 8192000000 10078465984 10534776832 1147414176 12.285 100 36 69 2 1200192128 45689296 7088656
09:43:23 61524 8192000000 10109740848 10570964992 1142576288 12.240 99 23 45 1 1209177424 61763904 4837232
09:45:26 89659 8192000000 10142248912 10608762880 1134973408 12.168 98 9 18 0 1210952896 73855152 2124336
09:48:14 29349 8192000000 10178236656 10627063808 1159984480 12.403 99 31 62 0 1256833328 89406976 7441872
09:49:50 29943 8192000000 10247229984 10691792896 1171537264 12.511 99 36 72 0 1321841200 141285120 9018816
09:51:36 34497 8192000000 10240909168 10715344896 1182591856 12.614 100 23 45 0 1342501888 154527360 5382672
09:53:22 41359 8192000000 10266412816 10733412352 1144453456 12.257 97 21 42 1 1317978704 168520752 5004496
09:55:18 51124 8192000000 10161307936 10644455424 1153711760 12.344 100 20 38 0 1325539456 167566816 4260880
09:57:09 20357 8192000000 10129588624 10564026368 1131883760 12.139 98 52 98 1 1184021568 42320944 9816864
09:58:45 156914 8192000000 10075162576 10525618176 1079388112 11.642 95 33 66 0 1160548416 74695184 6465120
10:02:23 56042 8192000000 10067328928 10539036672 1096982592 11.809 97 22 42 3 1155593648 54231744 4379312
10:04:35 47720 8192000000 10077000544 10541887488 1129409392 12.116 100 16 31 3 1180685264 47242448 4033424
10:06:23 65686 8192000000 10064354528 10511495168 1105725744 11.892 98 20 38 1 1149607200 39799712 4081744
10:08:29 93853 8192000000 10093665440 10554081280 1115098944 11.981 98 20 40 0 1190395232 71040976 4255312
10:11:25 54635 8192000000 10046920800 10523443200 1092045456 11.762 97 14 28 0 1118706560 23987216 2673888
10:13:19 40890 8192000000 10085182944 10515677184 1064266752 11.497 94 29 58 2 1142530432 72560032 5703648
10:15:10 26081 8192000000 10149154480 10592026624 1117804016 12.006 99 43 86 0 1249419328 122259408 9355904
10:16:43 61672 8192000000 10166245248 10604441600 1116569584 11.995 98 29 57 1 1255080560 132389168 6121808
10:18:45 60829 8192000000 10191344496 10632531968 1114171504 11.972 98 33 63 1 1250770656 129504864 7094288
10:21:00 35681 8192000000 10125150544 10579271680 1108328768 11.917 99 31 62 1 1205227664 90084256 6814640
10:22:39 90527 8192000000 10116032224 10620641280 1077944848 11.628 96 12 24 0 1121567904 41167968 2455088
10:25:58 145330 8192000000 10064402864 10498940928 1071052048 11.562 95 26 52 0 1118028496 41616432 5360016
10:29:31 39516 8192000000 10067888016 10542579712 1110733040 11.939 99 18 36 1 1165020480 50642464 3644976
10:31:19 83763 8192000000 10119538160 10565902336 1129765200 12.119 99 20 40 2 1202974800 68746288 4463312
10:33:55 77696 8192000000 10127086976 10613448704 1130119792 12.122 99 10 20 0 1239377600 106428848 2828960
10:36:15 68898 8192000000 9241851632 9564938240 379306688 4.425 34 7 14 0 399602640 18697408 1598544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-05-2022 10:39:17 to 14-05-2022 10:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 96 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-05-2022 10:39:33 to 14-05-2022 10:39:35.

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          213644
MON$OLDEST_ACTIVE               213645
MON$OLDEST_SNAPSHOT             213645
MON$NEXT_TRANSACTION            213645
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                       2069024
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:111: 
:112: mscs-test-161.budzdorov.ru    Sat May 14 10:37:27 2022
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:114: 
--- end of diff output ---

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

14.05.22 10:39:36. 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-05-14 10:39:39 No severe PSQL-related problems occured
14.05.22 10:39:40. End of report.