Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.06.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 16446080 kB
MemAvailable 31258524 kB
Buffers 0 kB
Cached 15301624 kB
SwapCached 2484 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-22 10:37:17
DTS_END                         2022-06-22 10:37:17.9680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45288
TRN_ID                          221617
ATT_ID                          670
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: 8685.50 23780 1042260
customer order: creation 168.71 478 20245
customer order: refuse 14.48 375 1738
order to supplier: creation 44.61 1311 5353
order to supplier: removal 3.38 143565 406
invoice (draft): creation 45.74 2922 5489
invoice (draft): removal 46.78 974 5614
invoice accept: apply 41.83 27170 5020
invoice accept: cancel 42.81 1176 5137
customer reserve: creation 3841.76 268 461011
customer reserve: removal 4145.92 93 497510
realization accept: apply 82.70 232 9924
realization accept: cancel 76.28 155 9154
payment from customer: creation 59.15 153 7098
payment from customer: removal 6.03 114 723
payment to supplier: creation 48.61 167 5833
payment to supplier: removal 3.70 85 444
service: total inventory turnovers 4.54 3108 545
service: total monetary turnovers 7.47 259 896
service: aggregate perf. data 0.97 15062 116
service: refresh index statistics 0.03 277942 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-06-2022 10:38:56 to 22-06-2022 10:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4479
TEST_TIME 2 3981
TEST_TIME 3 4751
TEST_TIME 4 4567
TEST_TIME 5 4426
TEST_TIME 6 4652
TEST_TIME 7 4486
TEST_TIME 8 4620
TEST_TIME 9 4799
TEST_TIME 10 5101
TEST_TIME 11 5299
TEST_TIME 12 5527
TEST_TIME 13 5622
TEST_TIME 14 5973
TEST_TIME 15 6310
TEST_TIME 16 6279
TEST_TIME 17 6821
TEST_TIME 18 7065
TEST_TIME 19 7362
TEST_TIME 20 7794
TEST_TIME 21 7904
TEST_TIME 22 8079
TEST_TIME 23 7907
TEST_TIME 24 8609
TEST_TIME 25 9599
TEST_TIME 26 10216
TEST_TIME 27 10643
TEST_TIME 28 10763
TEST_TIME 29 10435
TEST_TIME 30 10309
TEST_TIME 31 10300
TEST_TIME 32 10351
TEST_TIME 33 10231
TEST_TIME 34 10116
TEST_TIME 35 9948
TEST_TIME 36 9811
TEST_TIME 37 9639
TEST_TIME 38 9534
TEST_TIME 39 9570
TEST_TIME 40 9559
TEST_TIME 41 9505
TEST_TIME 42 9479
TEST_TIME 43 9412
TEST_TIME 44 9376
TEST_TIME 45 9328
TEST_TIME 46 9483
TEST_TIME 47 9419
TEST_TIME 48 9325
TEST_TIME 49 9422
TEST_TIME 50 9564
TEST_TIME 51 9545
TEST_TIME 52 9510
TEST_TIME 53 9446
TEST_TIME 54 9765
TEST_TIME 55 9731
TEST_TIME 56 9605
TEST_TIME 57 9473
TEST_TIME 58 9433
TEST_TIME 59 9429
TEST_TIME 60 9338
TEST_TIME 61 9251
TEST_TIME 62 9210
TEST_TIME 63 9170
TEST_TIME 64 9158
TEST_TIME 65 9171
TEST_TIME 66 9228
TEST_TIME 67 9185
TEST_TIME 68 9239
TEST_TIME 69 9264
TEST_TIME 70 9259
TEST_TIME 71 9279
TEST_TIME 72 9284
TEST_TIME 73 9256
TEST_TIME 74 9339
TEST_TIME 75 9315
TEST_TIME 76 9237
TEST_TIME 77 9207
TEST_TIME 78 9176
TEST_TIME 79 9130
TEST_TIME 80 9067
TEST_TIME 81 9069
TEST_TIME 82 9001
TEST_TIME 83 8964
TEST_TIME 84 8979
TEST_TIME 85 8981
TEST_TIME 86 8958
TEST_TIME 87 8930
TEST_TIME 88 8924
TEST_TIME 89 8958
TEST_TIME 90 8976
TEST_TIME 91 8988
TEST_TIME 92 8998
TEST_TIME 93 9081
TEST_TIME 94 9028
TEST_TIME 95 8957
TEST_TIME 96 8929
TEST_TIME 97 8904
TEST_TIME 98 8897
TEST_TIME 99 8860
TEST_TIME 100 8831
TEST_TIME 101 8826
TEST_TIME 102 8808
TEST_TIME 103 8820
TEST_TIME 104 8796
TEST_TIME 105 8803
TEST_TIME 106 8803
TEST_TIME 107 8786
TEST_TIME 108 8861
TEST_TIME 109 8905
TEST_TIME 110 8899
TEST_TIME 111 8971
TEST_TIME 112 8972
TEST_TIME 113 8953
TEST_TIME 114 8900
TEST_TIME 115 8915
TEST_TIME 116 8868
TEST_TIME 117 8814
TEST_TIME 118 8807
TEST_TIME 119 8767
TEST_TIME 120 8705

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-06-2022 10:39:00 to 22-06-2022 10:39:03.

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 1466646 1466646 0 0.00 0 21222 13 0 0
doc_list_biud 1466646 1466646 0 0.00 0 25168 37 0 0
sp_add_invoice_to_stock 5115 5020 95 1.85 38 278463 27170 0 95
sp_cancel_adding_invoice 5137 5137 0 0.00 5 70501 1176 0 0
sp_cancel_client_order 1738 1738 0 0.00 5 7497 375 0 0
sp_cancel_customer_reserve 497510 497510 0 0.00 1 25195 94 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 4 6812 114 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 4 1083 85 0 0
sp_cancel_supplier_invoice 5623 5614 9 0.16 2 23814 974 0 9
sp_cancel_supplier_order 406 406 0 0.00 11 1349112 143566 0 0
sp_cancel_write_off 9154 9154 0 0.00 2 15735 156 0 0
sp_client_order 20245 20245 0 0.00 6 51402 479 0 0
sp_customer_reserve 461011 461011 0 0.00 0 193576 268 0 0
sp_fill_shopping_cart 31072 31072 0 0.00 1 12904 69 0 0
sp_fill_shopping_cart_clo_res 460980 460980 0 0.00 0 22921 23 0 0
sp_get_clo_for_invoice 4989 4989 0 0.00 0 31929 324 0 0
sp_kill_cost_storno 15458 15458 0 0.00 0 11091 66 0 0
sp_kill_qstorno_ret_qs2qd 1010194 1010194 0 0.00 0 25110 42 0 0
sp_kill_qty_storno 534472 534472 0 0.00 0 25162 94 0 0
sp_lock_selected_doc 20725 20725 0 0.00 0 7822 7 0 0
sp_make_cost_storno 27844 27844 0 0.00 0 9472 66 0 0
sp_multiply_rows_for_pdistr 27844 27844 0 0.00 0 9845 34 0 0
sp_multiply_rows_for_qdistr 20245 20245 0 0.00 0 9289 204 0 0
sp_pay_from_customer 7098 7098 0 0.00 5 9880 154 0 0
sp_pay_to_supplier 5833 5833 0 0.00 7 10396 168 0 0
sp_payment_common 12931 12931 0 0.00 2 7663 38 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 0 7488 306 0 0
sp_qd_handle_on_invoice_upd_sts 10126 10126 0 0.00 0 18508 406 0 0
sp_qd_handle_on_reserve_upd_sts 9924 9924 0 0.00 0 14153 51 0 0
sp_reserve_write_off 9924 9924 0 0.00 8 14228 233 0 0
sp_supplier_invoice 5489 5489 0 0.00 10 75944 2922 0 0
sp_supplier_order 5353 5353 0 0.00 7 63610 1311 0 0
srv_aggregate_perf_data 116 116 0 0.00 2183 178018 15062 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 775 201345 37398 0 0
srv_find_qd_qs_mism 993533 993533 0 0.00 0 21219 12 0 0
srv_make_invnt_saldo 545 545 0 0.00 53 78728 3108 0 0
srv_make_money_saldo 896 896 0 0.00 1 11513 260 0 0
srv_recalc_idx_stat 4 4 0 0.00 129013 385437 277942 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5021 5021 0 0.00 1 17702 649 0 0
t$perf-norm:sp_cancel_adding_invoice 449 449 0 0.00 1 8652 424 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 2478 27 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1642 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 391 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 1141 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1047 24 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 98201 4738 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 1377 16 0 0
t$perf-norm:sp_client_order 20246 20246 0 0.00 0 5493 28 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 885 11 0 0
t$perf-norm:sp_pay_from_customer 7098 7098 0 0.00 0 4361 14 0 0
t$perf-norm:sp_pay_to_supplier 5833 5833 0 0.00 0 2960 14 0 0
t$perf-norm:sp_reserve_write_off 9924 9924 0 0.00 0 5783 17 0 0
t$perf-norm:sp_supplier_invoice 5489 5489 0 0.00 0 3637 23 0 0
t$perf-norm:sp_supplier_order 5353 5353 0 0.00 0 3134 25 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 429 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 104 4 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 628 11 0 0
t$perf-norm:srv_make_money_saldo 896 896 0 0.00 0 171 2 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 212 63 0 0
v_add_invoice_to_stock 10160 9970 190 1.87 0 5212 18 0 190
v_all_customers 37174 37174 0 0.00 0 115 3 0 0
v_all_suppliers 30264 30264 0 0.00 0 207 3 0 0
v_all_wares 40484 40484 0 0.00 0 209 3 0 0
v_cancel_adding_invoice 898 898 0 0.00 0 1113 14 0 0
v_cancel_client_order 3476 3476 0 0.00 0 3002 9 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 1643 12 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2317 13 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 554 8 0 0
v_cancel_supplier_invoice 826 808 18 2.17 0 471 16 0 18
v_cancel_supplier_order 812 812 0 0.00 0 1009 9 0 0
v_cancel_write_off 1928 1928 0 0.00 0 2016 21 0 0
v_max_id_clo_ord 5348 5348 0 0.00 0 8067 5 0 0
v_max_id_clo_res 927 927 0 0.00 0 1438 15 0 0
v_max_id_ord_sup 5482 5482 0 0.00 0 4400 8 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 389 6 0 0
v_max_non_paid_realizn 3695 3695 0 0.00 0 560 6 0 0
v_min_id_clo_ord 5348 5348 0 0.00 0 1527 7 0 0
v_min_id_clo_res 927 927 0 0.00 0 7393 28 0 0
v_min_id_ord_sup 5482 5482 0 0.00 0 4421 11 0 0
v_min_non_paid_invoice 5833 5833 0 0.00 0 3011 13 0 0
v_min_non_paid_realizn 7098 7098 0 0.00 0 7149 16 0 0
v_our_firm 10116 10116 0 0.00 0 70 3 0 0
v_reserve_write_off 19842 19842 0 0.00 0 4509 9 0 0
x_lock_depdocs_on_canc_invoice 10347 10347 0 0.00 0 20510 130 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 13988 328 0 0
x_make_qstorno_1000_1200 5353 5353 0 0.00 2 63584 1171 0 0
x_make_qstorno_1200_2000 5489 5489 0 0.00 4 75927 2726 0 0
x_make_qstorno_2100_3300 461011 461011 0 0.00 0 193476 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-06-2022 10:39:04 to 22-06-2022 10:39:08.

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:21 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 30956 8192000000 9729663376 10033262592 810829408 9.006 100 86 172 0 839320192 8066704 20424080
08:09:58 22357 8192000000 9777346800 10090934272 852631136 9.426 100 89 178 0 886394496 12950000 20813360
08:11:29 20239 8192000000 9815690704 10139123712 884372080 9.743 100 88 176 0 929323152 24549344 20401728
08:12:51 9113 8192000000 9864629168 10203516928 922491968 10.121 100 71 142 1 973602960 35531776 15579216
08:14:02 5200 8192000000 9926641296 10272374784 961793168 10.507 100 87 172 2 1045302400 65432352 18076880
08:15:10 2910 8192000000 9977387744 10331344896 995426160 10.834 100 91 177 1 1098057376 84892848 17738368
08:16:14 1023 8192000000 9979772304 10342014976 1021493872 11.086 100 87 163 2 1103093392 64461120 17138400
08:17:17 1015 8192000000 10032823664 10403393536 1043134736 11.295 100 92 182 0 1144054912 82554992 18365184
08:18:19 1431 8192000000 10036971680 10415988736 1055086112 11.409 100 93 177 2 1154671648 82230640 17354896
08:19:22 8576 8192000000 10037794816 10409955328 1063513200 11.490 100 87 165 2 1158314832 79291184 15510448
08:20:32 1197 8192000000 10021595392 10399981568 1071821008 11.569 100 89 166 0 1145143488 57122560 16199920
08:21:34 1298 8192000000 10054398112 10443821056 1080088672 11.648 100 90 175 1 1174204640 76071104 18044864
08:22:37 1107 8192000000 10052600128 10438803456 1087893424 11.723 100 92 175 1 1169776880 63898624 17984832
08:23:39 5386 8192000000 10034266320 10426716160 1088429152 11.728 100 64 114 2 1148953472 49109488 11414832
08:24:46 1293 8192000000 10079347520 10470776832 1091074464 11.753 100 86 163 2 1196354768 89886624 15393680
08:25:49 2589 8192000000 10058825056 10447372288 1092540832 11.767 100 80 146 4 1178017360 70425184 15051344
08:26:52 1462 8192000000 10069392960 10457853952 1087274592 11.717 100 97 182 1 1194854848 88523376 19056880
08:27:54 1335 8192000000 10057036112 10441162752 1092552496 11.767 100 94 179 3 1175942752 64839040 18551216
08:28:57 3575 8192000000 10029000176 10420748288 1080972592 11.657 99 65 122 0 1152106864 58919184 12215088
08:30:01 1050 8192000000 10041824896 10427994112 1080208720 11.649 100 84 159 1 1164547376 66765920 17572736
08:31:03 1025 8192000000 10035718432 10415165440 1068465280 11.537 100 97 184 0 1156659328 69377440 18816608
08:32:05 1432 8192000000 10028523328 10405838848 1055927632 11.417 100 96 181 1 1149678608 74977056 18773920
08:33:08 1048 8192000000 9994956800 10370793472 1042591632 11.290 100 86 158 3 1117831376 58004480 17235264
08:34:09 1178 8192000000 10001155488 10372288512 1025962048 11.130 100 95 183 0 1121720928 76580848 19178032
08:35:11 1428 8192000000 9966264112 10332729344 1024296112 11.113 100 97 185 0 1086397248 43091184 19009952
08:36:14 1092 8192000000 10001749584 10386489344 1040163712 11.266 100 79 156 2 1121562368 64626368 16772288
08:37:15 18813 8192000000 9990023728 10379137024 1036134816 11.227 99 61 121 0 1117522032 69230560 12156656
08:38:47 11619 8192000000 10003907936 10378534912 1054816800 11.407 100 85 154 0 1119642736 50164800 14661136
08:40:04 1598 8192000000 10043131008 10427019264 1073441952 11.585 100 73 141 0 1162432896 74124192 14866752
08:41:09 6843 8192000000 10070312544 10467901440 1090273408 11.745 100 67 132 1 1197204640 92644064 14287168
08:42:23 25138 8192000000 10136227840 10531266560 1095231824 11.792 99 56 110 2 1242275280 135997824 11045632
08:44:00 1035 8192000000 10177650416 10562789376 1121173648 12.038 100 92 169 3 1292397440 152709952 18513840
08:45:05 3604 8192000000 10243388784 10632220672 1133813424 12.157 100 84 160 0 1357950784 207250816 16886544
08:46:14 1227 8192000000 10302694160 10696884224 1149429984 12.304 100 82 158 3 1415610592 249526720 16653888
08:47:19 771 8192000000 10334797136 10732326912 1162260848 12.424 100 97 189 1 1446121632 262919104 20941680
08:48:24 879 8192000000 10355751632 10753359872 1172536320 12.521 100 99 189 0 1467561728 274215872 20809536
08:49:26 2958 8192000000 10407286000 10804477952 1178327440 12.575 100 86 167 0 1517806704 320018112 19461152
08:50:32 1895 8192000000 10446459744 10845765632 1176538528 12.558 100 85 167 3 1549707552 353500560 19668464
08:51:38 2487 8192000000 10492949136 10895765504 1180762736 12.597 100 81 162 4 1593771872 394251040 18758096
08:52:43 3313 8192000000 10477024880 10886930432 1174972336 12.543 100 71 140 0 1586922432 394575632 17374464
08:53:50 1075 8192000000 10537391696 10959216640 1179540736 12.586 100 95 187 1 1644771168 443350368 21880064
08:54:54 1550 8192000000 10503706368 10933448704 1178722608 12.578 100 74 141 1 1613907776 418279360 16905808
08:55:58 5637 8192000000 10502274128 10944708608 1183285696 12.621 100 61 120 1 1609499696 411331536 14882464
08:57:12 3147 8192000000 10463710912 10916864000 1176217616 12.555 99 66 121 1 1565531920 374875056 14439248
08:58:20 9487 8192000000 10508492160 10956484608 1186578192 12.652 100 54 106 0 1615102240 415374976 13149072
08:59:33 27349 8192000000 10523158656 10963251200 1152854176 12.336 98 42 84 1 1608050704 443923584 11272944
09:01:02 10459 8192000000 10520695920 11014246400 1157888960 12.383 100 50 100 1 1626633264 455297856 13446448
09:02:14 33189 8192000000 10295264832 10789863424 1134568624 12.164 99 30 60 1 1447261776 304582048 8111104
09:03:48 39403 8192000000 10138746288 10597744640 1123171904 12.057 99 12 25 1 1272409296 146308272 2929120
09:05:28 39231 8192000000 10112972576 10551386112 1143803680 12.251 100 33 66 1 1205223824 53907312 7512832
09:08:02 66258 8192000000 10122732464 10589949952 1110311024 11.935 98 35 72 1 1203973856 86852256 6810576
09:10:09 201344 8192000000 10093368064 10556076032 1119734256 12.024 98 26 51 0 1169185456 44093488 5357712
09:14:31 158775 8192000000 10088935920 10608959488 1061685248 11.473 93 19 38 1 1177880400 111965392 4229760
09:18:47 14858 8192000000 10076103472 10543448064 1128232752 12.105 99 23 45 1 1178753760 45813872 4707136
09:20:04 42556 8192000000 10083463280 10549104640 1138509376 12.202 100 27 53 2 1185699104 41131552 6058176
09:21:55 49419 8192000000 10137902944 10605694976 1127328480 12.096 98 17 35 1 1218670992 87940176 3402336
09:23:47 25596 8192000000 10082787360 10521509888 1139005952 12.206 99 47 88 0 1178232464 30765584 8460928
09:25:15 23447 8192000000 10154220224 10587561984 1141092016 12.226 99 46 92 0 1238619824 87089728 10438080
09:26:46 69649 8192000000 10086081408 10556682240 1120987696 12.036 98 13 26 0 1218557472 94355424 3214352
09:29:12 63404 8192000000 10172181168 10616553472 1126738464 12.091 98 40 80 0 1217965648 83073312 8153872
09:31:42 94115 8192000000 10094890304 10522447872 1126023552 12.084 98 35 69 0 1179402592 46372816 7006224
09:34:20 98889 8192000000 10100174560 10555240448 1149104576 12.301 100 43 68 1 1246812448 89423200 8284672
09:37:01 59744 8192000000 10124886592 10632450048 1158712528 12.391 100 10 20 0 1231807600 70981696 2113376
09:39:03 62917 8192000000 10131985312 10606104576 1130410048 12.125 97 18 36 1 1216598128 81408736 4779344
09:41:07 59823 8192000000 10125585504 10581774336 1124578624 12.070 97 20 39 3 1230701248 101977280 4145344
09:43:25 35916 8192000000 10102265984 10555072512 1157068704 12.376 100 21 40 1 1210828944 49425264 4334976
09:45:02 19856 8192000000 10153387824 10576879616 1152194736 12.330 100 46 92 0 1236873200 75163936 9514528
09:46:30 35592 8192000000 10080056256 10521522176 1106870912 11.903 96 31 62 0 1146174592 32707680 6596000
09:48:10 19389 8192000000 10092519584 10540916736 1140622048 12.221 100 46 92 1 1209204416 59564000 9018368
09:49:31 17376 8192000000 10078065040 10510782464 1106002304 11.895 98 46 92 0 1167288432 51853520 9432608
09:50:49 36440 8192000000 10104694944 10549649408 1104726256 11.882 98 45 90 1 1212527584 98378512 9422816
09:52:27 36828 8192000000 10105393680 10537558016 1084817168 11.693 97 46 92 1 1138720048 44970768 8932112
09:55:32 41260 8192000000 10051177200 10500190208 1102684848 11.863 99 22 44 1 1155338720 48289376 4364496
09:59:10 42468 8192000000 10033897056 10456043520 1097952240 11.818 98 23 43 1 1132376304 29471424 4952640
10:00:53 35300 8192000000 10075508272 10523205632 1108499456 11.918 99 32 64 1 1170886272 55732480 6654336
10:02:38 30834 8192000000 10064053504 10504966144 1115951760 11.989 99 27 54 0 1163728096 41696576 6079760
10:04:09 34269 8192000000 10096722688 10530545664 1100698352 11.844 99 47 92 1 1169384928 58998800 9687776
10:05:44 42560 8192000000 10097890032 10551259136 1089431328 11.737 98 35 70 0 1186925344 90237248 7256768
10:07:28 27450 8192000000 10065786032 10489794560 1101031376 11.847 99 39 76 1 1152686896 44142272 7513248
10:08:59 36883 8192000000 10125715568 10573266944 1112987424 11.961 99 38 75 2 1212682944 91610208 8085312
10:10:38 34776 8192000000 10056158800 10483240960 1126176768 12.085 100 18 34 0 1155176432 24953792 4045872
10:12:16 100111 8192000000 10041940048 10480488448 1120060336 12.028 99 34 68 0 1168890064 41844352 6985376
10:14:57 48693 8192000000 10070926880 10542391296 1136878192 12.186 100 14 28 0 1196693056 57046400 2768464
10:16:46 74601 8192000000 10072287392 10560024576 1125500000 12.079 99 6 12 0 1162127488 35084864 1542624
10:19:01 23335 8192000000 10120960096 10563780608 1150227536 12.312 100 28 53 1 1217102992 61381616 5493840
10:20:34 22305 8192000000 10147381168 10593853440 1152314016 12.331 99 30 60 0 1238464576 79648672 6501888
10:21:57 32429 8192000000 10175373280 10623881216 1161340016 12.416 100 33 66 1 1263306352 94690144 7276192
10:23:30 24096 8192000000 10173202016 10607955968 1128209008 12.104 97 34 68 1 1241157328 105327216 7621104
10:24:59 33953 8192000000 10203103184 10639765504 1130627872 12.127 99 45 90 0 1287010576 146584912 9797792
10:26:34 32244 8192000000 10108791792 10562482176 1089172976 11.735 96 38 74 1 1191697664 94690448 7834240
10:28:07 55600 8192000000 10093687792 10578006016 1091796672 11.760 98 37 74 1 1221717600 122027904 7893024
10:30:03 28209 8192000000 10104712064 10524155904 1108876240 11.922 99 48 96 0 1178990144 60797200 9316704
10:31:33 154139 8192000000 10056372272 10536050688 1112616896 11.957 98 16 32 0 1156761872 41218320 2926656
10:35:07 60740 8192000000 10037031088 10492182528 1106560192 11.900 98 13 25 0 1145142768 36596592 1985984
10:37:12 19074 8192000000 9039140160 9300865024 196059264 2.337 18 3 4 1 213634000 16892960 681776

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-06-2022 10:39:10 to 22-06-2022 10:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-06-2022 10:39:27 to 22-06-2022 10:39:29.

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          223492
MON$OLDEST_ACTIVE               223493
MON$OLDEST_SNAPSHOT             223493
MON$NEXT_TRANSACTION            223493
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                       2012992
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2782 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2782 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2782 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 ---
--- end of diff output ---

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

22.06.22 10:39:30. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-22 10:39:33 No severe PSQL-related problems occured
22.06.22 10:39:34. End of report.