Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.03.2023 01: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 15123892 kB
MemAvailable 30428272 kB
Buffers 0 kB
Cached 16566348 kB
SwapCached 3032 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2023-03-11 01:38:46
DTS_END                         2023-03-11 01:38:46.0410
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(57:17)
IP                              ::1/47652
TRN_ID                          247190
ATT_ID                          693
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: 9492.70 15025 1139124
customer order: creation 179.47 327 21536
customer order: refuse 14.78 351 1773
order to supplier: creation 48.03 1231 5763
order to supplier: removal 3.72 85151 446
invoice (draft): creation 47.44 3297 5693
invoice (draft): removal 55.77 680 6692
invoice accept: apply 42.88 17127 5146
invoice accept: cancel 50.39 790 6047
customer reserve: creation 3866.77 173 464012
customer reserve: removal 4880.40 51 585648
realization accept: apply 87.16 205 10459
realization accept: cancel 81.56 108 9787
payment from customer: creation 62.28 148 7473
payment from customer: removal 5.54 66 665
payment to supplier: creation 50.96 160 6115
payment to supplier: removal 3.45 82 414
service: total inventory turnovers 4.70 1237 564
service: total monetary turnovers 6.58 55 789
service: aggregate perf. data 0.82 22558 98
service: refresh index statistics 0.03 166707 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-03-2023 01:39:41 to 11-03-2023 01:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2135
TEST_TIME 2 1958
TEST_TIME 3 2344
TEST_TIME 4 3087
TEST_TIME 5 3991
TEST_TIME 6 3867
TEST_TIME 7 3928
TEST_TIME 8 4084
TEST_TIME 9 4499
TEST_TIME 10 5099
TEST_TIME 11 5536
TEST_TIME 12 5708
TEST_TIME 13 6731
TEST_TIME 14 7223
TEST_TIME 15 7125
TEST_TIME 16 7289
TEST_TIME 17 7596
TEST_TIME 18 7642
TEST_TIME 19 8114
TEST_TIME 20 8446
TEST_TIME 21 8727
TEST_TIME 22 9006
TEST_TIME 23 8917
TEST_TIME 24 9334
TEST_TIME 25 9379
TEST_TIME 26 9459
TEST_TIME 27 9489
TEST_TIME 28 9939
TEST_TIME 29 10548
TEST_TIME 30 10351
TEST_TIME 31 10331
TEST_TIME 32 10321
TEST_TIME 33 10240
TEST_TIME 34 10071
TEST_TIME 35 10151
TEST_TIME 36 10336
TEST_TIME 37 10257
TEST_TIME 38 10073
TEST_TIME 39 9906
TEST_TIME 40 9918
TEST_TIME 41 9897
TEST_TIME 42 9885
TEST_TIME 43 9854
TEST_TIME 44 9781
TEST_TIME 45 9787
TEST_TIME 46 9795
TEST_TIME 47 9770
TEST_TIME 48 9685
TEST_TIME 49 9761
TEST_TIME 50 9717
TEST_TIME 51 9609
TEST_TIME 52 9575
TEST_TIME 53 9499
TEST_TIME 54 9462
TEST_TIME 55 9411
TEST_TIME 56 9354
TEST_TIME 57 9358
TEST_TIME 58 9338
TEST_TIME 59 9379
TEST_TIME 60 9380
TEST_TIME 61 9371
TEST_TIME 62 9436
TEST_TIME 63 9458
TEST_TIME 64 9629
TEST_TIME 65 9711
TEST_TIME 66 9677
TEST_TIME 67 9656
TEST_TIME 68 9693
TEST_TIME 69 9786
TEST_TIME 70 9741
TEST_TIME 71 9824
TEST_TIME 72 9902
TEST_TIME 73 9782
TEST_TIME 74 9715
TEST_TIME 75 9730
TEST_TIME 76 9670
TEST_TIME 77 9660
TEST_TIME 78 9644
TEST_TIME 79 9605
TEST_TIME 80 9583
TEST_TIME 81 9620
TEST_TIME 82 9548
TEST_TIME 83 9507
TEST_TIME 84 9520
TEST_TIME 85 9531
TEST_TIME 86 9537
TEST_TIME 87 9528
TEST_TIME 88 9523
TEST_TIME 89 9554
TEST_TIME 90 9524
TEST_TIME 91 9544
TEST_TIME 92 9589
TEST_TIME 93 9599
TEST_TIME 94 9610
TEST_TIME 95 9635
TEST_TIME 96 9707
TEST_TIME 97 9664
TEST_TIME 98 9644
TEST_TIME 99 9648
TEST_TIME 100 9706
TEST_TIME 101 9655
TEST_TIME 102 9606
TEST_TIME 103 9667
TEST_TIME 104 9611
TEST_TIME 105 9619
TEST_TIME 106 9583
TEST_TIME 107 9547
TEST_TIME 108 9522
TEST_TIME 109 9524
TEST_TIME 110 9487
TEST_TIME 111 9430
TEST_TIME 112 9418
TEST_TIME 113 9437
TEST_TIME 114 9436
TEST_TIME 115 9419
TEST_TIME 116 9432
TEST_TIME 117 9517
TEST_TIME 118 9520
TEST_TIME 119 9501
TEST_TIME 120 9494

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-03-2023 01:39:45 to 11-03-2023 01:39:48.

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 1570140 1570140 0 0.00 0 41075 6 0 0
doc_list_biud 1570135 1570135 0 0.00 0 24489 25 0 0
sp_add_invoice_to_stock 5233 5146 87 1.66 31 166544 17127 0 87
sp_cancel_adding_invoice 6047 6047 0 0.00 4 63567 791 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 9981 352 0 0
sp_cancel_customer_reserve 585648 585648 0 0.00 0 19405 51 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 2 7569 66 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 2 5953 82 0 0
sp_cancel_supplier_invoice 6698 6692 6 0.08 1 18237 680 0 6
sp_cancel_supplier_order 446 446 0 0.00 8 927522 85151 0 0
sp_cancel_write_off 9787 9787 0 0.00 1 39209 109 0 0
sp_client_order 21536 21536 0 0.00 3 13575 327 0 0
sp_customer_reserve 464012 464012 0 0.00 0 107968 174 0 0
sp_fill_shopping_cart 32992 32992 0 0.00 1 16565 39 0 0
sp_fill_shopping_cart_clo_res 464010 464010 0 0.00 0 11842 12 0 0
sp_get_clo_for_invoice 5138 5138 0 0.00 0 35091 259 0 0
sp_kill_cost_storno 16913 16913 0 0.00 0 39113 53 0 0
sp_kill_qstorno_ret_qs2qd 1188221 1188221 0 0.00 0 20546 28 0 0
sp_kill_qty_storno 625988 625988 0 0.00 0 24484 61 0 0
sp_lock_selected_doc 21475 21475 0 0.00 0 8281 5 0 0
sp_make_cost_storno 29185 29185 0 0.00 0 40899 64 0 0
sp_multiply_rows_for_pdistr 29183 29183 0 0.00 0 39845 37 0 0
sp_multiply_rows_for_qdistr 21536 21536 0 0.00 0 9791 133 0 0
sp_pay_from_customer 7473 7473 0 0.00 4 40470 148 0 0
sp_pay_to_supplier 6115 6115 0 0.00 4 41838 161 0 0
sp_payment_common 13586 13586 0 0.00 1 39766 53 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 9972 327 0 0
sp_qd_handle_on_invoice_upd_sts 11184 11184 0 0.00 0 24478 331 0 0
sp_qd_handle_on_reserve_upd_sts 10458 10458 0 0.00 0 14459 60 0 0
sp_reserve_write_off 10459 10459 0 0.00 6 40490 206 0 0
sp_supplier_invoice 5693 5693 0 0.00 6 106415 3297 0 0
sp_supplier_order 5763 5763 0 0.00 4 35959 1232 0 0
srv_aggregate_perf_data 98 98 0 0.00 3239 195934 22558 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1347 102056 43629 0 0
srv_find_qd_qs_mism 1090730 1090730 0 0.00 0 17089 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 69 44154 1238 0 0
srv_make_money_saldo 789 789 0 0.00 0 13697 55 0 0
srv_recalc_idx_stat 4 4 0 0.00 111722 262375 166708 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5146 5146 0 0.00 0 26607 171 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 5745 82 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 1997 11 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 1659 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 726 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 8068 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 3924 19 0 0
t$perf-norm:sp_cancel_supplier_order 446 446 0 0.00 0 20031 1408 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 5627 12 0 0
t$perf-norm:sp_client_order 21537 21537 0 0.00 0 13661 18 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 3958 8 0 0
t$perf-norm:sp_pay_from_customer 7473 7473 0 0.00 0 22760 16 0 0
t$perf-norm:sp_pay_to_supplier 6115 6115 0 0.00 0 4441 8 0 0
t$perf-norm:sp_reserve_write_off 10459 10459 0 0.00 0 5794 14 0 0
t$perf-norm:sp_supplier_invoice 5693 5693 0 0.00 0 4399 16 0 0
t$perf-norm:sp_supplier_order 5763 5763 0 0.00 0 5361 14 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 513 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 912 22 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 7930 33 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7955 1990 0 0
v_add_invoice_to_stock 10440 10266 174 1.66 0 8861 17 0 174
v_all_customers 39396 39396 0 0.00 0 22 1 0 0
v_all_suppliers 31468 31468 0 0.00 0 24 1 0 0
v_all_wares 43072 43072 0 0.00 0 61 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 6195 17 0 0
v_cancel_client_order 3546 3546 0 0.00 0 1762 6 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 534 3 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 1160 4 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 3258 12 0 0
v_cancel_supplier_invoice 856 844 12 1.40 0 1522 20 0 12
v_cancel_supplier_order 892 892 0 0.00 0 2194 11 0 0
v_cancel_write_off 1974 1974 0 0.00 0 2877 15 0 0
v_max_id_clo_ord 5763 5763 0 0.00 0 312 2 0 0
v_max_id_clo_res 998 998 0 0.00 0 4990 20 0 0
v_max_id_ord_sup 5693 5693 0 0.00 0 4030 4 0 0
v_max_non_paid_invoice 1835 1835 0 0.00 0 4290 5 0 0
v_max_non_paid_realizn 3888 3888 0 0.00 0 39675 14 0 0
v_min_id_clo_ord 5763 5763 0 0.00 0 3731 8 0 0
v_min_id_clo_res 998 998 0 0.00 0 5967 13 0 0
v_min_id_ord_sup 5693 5693 0 0.00 0 4774 6 0 0
v_min_non_paid_invoice 6113 6113 0 0.00 0 39188 30 0 0
v_min_non_paid_realizn 7470 7470 0 0.00 0 39345 43 0 0
v_our_firm 10842 10842 0 0.00 0 22 1 0 0
v_reserve_write_off 20916 20916 0 0.00 0 6478 7 0 0
x_lock_depdocs_on_canc_invoice 12317 12317 0 0.00 0 14515 106 0 0
x_lock_depdocs_on_canc_sup_ord 446 446 0 0.00 0 27709 445 0 0
x_make_qstorno_1000_1200 5763 5763 0 0.00 1 35950 1150 0 0
x_make_qstorno_1200_2000 5693 5693 0 0.00 1 106356 3155 0 0
x_make_qstorno_2100_3300 464012 464012 0 0.00 0 107968 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-03-2023 01:39:49 to 11-03-2023 01:39:53.

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
23:08:50 13 8192000000 8814206336 8830754816 18154448 0.221 5 4 5 0 18382560 88816 139296
23:09:51 6591 8192000000 9897711536 10207842304 982224448 10.706 100 84 168 1 1009560304 5817136 21518720
23:11:01 35222 8192000000 9944462496 10265198592 1024508208 11.116 100 91 182 0 1056252240 8237872 23506160
23:12:43 10827 8192000000 9966781600 10300932096 1058113536 11.438 100 82 163 0 1087875424 9605952 20155936
23:13:57 6256 8192000000 10023066624 10369683456 1111626496 11.948 100 85 168 0 1143696960 11607840 20462624
23:15:09 8659 8192000000 10080410000 10439843840 1165269056 12.453 100 78 156 0 1195541248 12009472 18262720
23:16:20 2499 8192000000 10125302064 10492981248 1211812064 12.886 100 85 169 1 1244176448 13622720 18741664
23:17:26 2010 8192000000 10151470400 10526670848 1243044832 13.174 100 87 174 0 1276296928 13921776 19330320
23:18:30 1889 8192000000 10186347568 10567421952 1270928352 13.430 100 82 162 0 1302735824 13638816 18168656
23:19:34 1484 8192000000 10204395568 10592346112 1295143584 13.651 100 79 153 0 1324352464 12034736 17174144
23:20:40 3850 8192000000 10203806544 10596970496 1296185088 13.661 100 77 150 1 1322862048 10189120 16487840
23:21:44 5358 8192000000 10187346096 10602795008 1301845456 13.712 100 33 66 0 1317531696 8263888 7422352
23:22:53 3861 8192000000 10202279392 10610823168 1309913728 13.785 100 41 79 1 1327229968 7722592 9593648
23:23:59 7537 8192000000 10195316928 10616434688 1316766704 13.847 100 17 34 0 1326459520 5896240 3796576
23:25:08 60567 8192000000 10213327840 10634665984 1325085312 13.923 100 34 66 0 1337695776 5074768 7535696
23:27:11 4370 8192000000 10208280832 10623594496 1306122160 13.751 99 38 76 0 1322803280 7567936 9113184
23:28:22 5678 8192000000 10189542064 10613317632 1299017968 13.686 99 20 39 0 1308992112 5676864 4297280
23:29:28 3801 8192000000 10175393312 10568732672 1246409920 13.205 96 50 100 5 1263203728 5265120 11528688
23:30:37 7600 8192000000 10168895664 10581975040 1264362128 13.370 98 20 39 0 1275189648 6796752 4030768
23:31:46 7535 8192000000 10136409344 10536738816 1216400336 12.928 97 29 54 0 1229401568 6384560 6616672
23:32:57 11145 8192000000 10146764480 10543104000 1223320032 12.992 97 27 54 1 1234915392 5331552 6263808
23:34:09 52379 8192000000 10163055488 10566299648 1265583072 13.381 99 35 71 0 1279337488 4808336 8946080
23:36:02 7055 8192000000 10183369872 10596012032 1294277616 13.643 100 21 42 0 1304629824 5587360 4764848
23:37:09 6293 8192000000 10189882288 10607353856 1305160928 13.742 100 24 45 0 1315345376 5001056 5183392
23:38:16 13814 8192000000 10220992160 10637516800 1329943712 13.967 100 10 22 0 1336487104 4660544 1882848
23:39:33 14699 8192000000 10250511520 10661658624 1346296688 14.114 100 39 75 0 1361759552 6591472 8871392
23:41:26 66763 8192000000 10267243648 10690818048 1359856304 14.236 100 31 62 1 1376970576 9899904 7214368
23:43:37 39243 8192000000 10282152752 10717560832 1379743872 14.414 100 22 42 0 1398228320 13363040 5121408
23:45:22 5579 8192000000 10306746032 10726555648 1396064160 14.560 100 39 77 3 1419523072 14131216 9327696
23:46:42 12544 8192000000 10317444624 10742419456 1405619312 14.645 100 53 102 2 1434672592 17060208 11993072
23:48:01 6430 8192000000 10319879216 10745946112 1403588336 14.627 100 49 95 0 1434582048 19427888 11565824
23:49:10 5944 8192000000 10325113584 10747170816 1401353120 14.607 100 56 110 1 1438016688 23387584 13275984
23:50:21 4517 8192000000 10334484288 10758594560 1407753680 14.664 100 61 124 0 1445586080 22662864 15169536
23:51:28 1715 8192000000 10329172400 10741338112 1384614752 14.458 99 87 168 0 1430564160 25946720 20002688
23:52:35 1342 8192000000 10323733808 10738642944 1389255280 14.499 100 84 167 1 1432232528 21901392 21075856
23:53:42 2284 8192000000 10304754768 10717048832 1368423648 14.313 99 69 138 3 1408591232 23672928 16494656
23:54:48 5811 8192000000 10330548176 10746150912 1389156736 14.498 100 72 144 0 1435234464 28582480 17495248
23:56:01 2948 8192000000 10342713440 10756272128 1397896576 14.576 100 76 152 0 1446468832 28971024 19601232
23:57:11 1702 8192000000 10338593504 10745937920 1387169568 14.481 100 90 181 1 1443950864 34751856 22029440
23:58:16 3172 8192000000 10309017040 10714947584 1362422160 14.259 100 83 161 1 1411045552 28576624 20046768
23:59:28 2464 8192000000 10288860944 10697392128 1342094096 14.076 99 74 149 3 1396637904 34244992 20298816
00:00:35 10644 8192000000 10292448560 10706784256 1355520208 14.197 99 33 66 0 1389411616 25784688 8106720
00:01:49 10161 8192000000 10294166256 10715561984 1348355168 14.133 99 32 62 1 1391558784 34705520 8498096
00:03:03 18123 8192000000 10288274208 10712145920 1345066800 14.103 99 30 59 0 1382446016 29214496 8164720
00:04:24 10782 8192000000 10293253104 10730487808 1366138608 14.292 100 22 44 0 1398186784 25855360 6192816
00:05:43 32145 8192000000 10266522432 10703286272 1332063504 13.986 99 15 30 0 1369734848 33119840 4551504
00:07:22 55600 8192000000 10260556528 10703921152 1316086720 13.841 97 3 6 0 1330365776 13686496 592560
00:09:19 99142 8192000000 10284258768 10731769856 1348000736 14.129 98 21 43 1 1363279760 10241424 5037600
00:12:01 81044 8192000000 10310670864 10744291328 1363606336 14.270 98 18 36 0 1382216208 14477216 4132656
00:14:28 83073 8192000000 10284274528 10730831872 1360706480 14.244 99 12 24 0 1385323424 21740688 2876256
00:17:48 99635 8192000000 10303153568 10745462784 1374089472 14.364 99 27 52 1 1393425200 12784544 6551184
00:20:29 48993 8192000000 10295611600 10755244032 1390304496 14.509 100 20 40 1 1406521856 11198848 5018512
00:22:21 26618 8192000000 10314786768 10756382720 1396142688 14.561 100 47 94 0 1415028304 7725808 11159808
00:23:50 61781 8192000000 10267949120 10724048896 1339536720 14.053 96 23 46 0 1360699408 15598656 5564032
00:25:53 70461 8192000000 10286425200 10742915072 1371531104 14.341 98 11 22 0 1383481760 8433984 3516672
00:28:06 28685 8192000000 10287277488 10737422336 1359155728 14.230 98 21 41 0 1370476912 7375328 3945856
00:29:36 74434 8192000000 10301209104 10755739648 1389139136 14.498 100 24 46 0 1403764176 8517776 6107264
00:31:56 21611 8192000000 10287578304 10759647232 1390246208 14.508 100 18 36 0 1406528560 12191760 4090592
00:33:51 54800 8192000000 10284907136 10748215296 1361447072 14.250 98 18 36 1 1371625408 6515328 3663008
00:35:50 39364 8192000000 10276429856 10739421184 1334848896 14.011 96 12 24 0 1344088528 6741168 2498464
00:37:29 27562 8192000000 10239274864 10690977792 1324189520 13.915 97 26 52 1 1338076560 8007056 5879984
00:38:57 15794 8192000000 10284734384 10732113920 1370842784 14.335 100 42 84 1 1396109248 15986784 9279680
00:40:26 38994 8192000000 10291033872 10752208896 1381880144 14.433 100 26 52 0 1408602368 20431056 6291168
00:42:48 64925 8192000000 10283771536 10739499008 1321388064 13.889 96 14 28 0 1348575088 24132960 3054064
00:44:57 40873 8192000000 10275597776 10715860992 1318961376 13.867 96 22 45 0 1331862288 8006560 4894352
00:46:40 48857 8192000000 10268840704 10712780800 1321892848 13.894 97 32 64 0 1343678576 14589040 7196688
00:48:30 12512 8192000000 10275070048 10715639808 1333555536 13.999 98 30 60 1 1355009696 14908032 6546128
00:49:43 54532 8192000000 10275662992 10734882816 1343839168 14.092 99 8 16 0 1365412848 20060496 1513184
00:51:38 97952 8192000000 10250935200 10696294400 1277884096 13.494 94 24 46 1 1291127984 8021024 5222864
00:54:16 57556 8192000000 10270809216 10727735296 1365948992 14.291 100 19 38 2 1381900880 11678400 4273488
00:56:28 54481 8192000000 10273016192 10736164864 1343803824 14.092 98 16 32 0 1367764176 20116272 3844080
00:58:26 50782 8192000000 10283076480 10729496576 1337498112 14.035 98 9 18 0 1354802608 14960432 2344064
01:00:47 65740 8192000000 10268085120 10721869824 1355965936 14.201 100 16 32 0 1372532880 12302624 4264320
01:02:53 85344 8192000000 10275552320 10740772864 1350328928 14.150 99 6 12 0 1371477968 20088128 1060912
01:05:19 84976 8192000000 10277025152 10734272512 1357176640 14.212 99 9 18 0 1371585872 11925968 2483264
01:07:44 40847 8192000000 10294129872 10737950720 1363638640 14.270 99 40 77 0 1382455552 10001344 8815568
01:09:30 62399 8192000000 10287326736 10754371584 1375487056 14.376 99 15 30 0 1388914784 10201712 3226016
01:11:33 60838 8192000000 10310701024 10763608064 1397114704 14.569 100 14 28 0 1410958288 11249408 2594176
01:13:34 28507 8192000000 10307970240 10759712768 1368481824 14.313 98 30 60 0 1386207600 11196224 6529552
01:15:03 74500 8192000000 10325250400 10767384576 1395492848 14.555 100 37 72 1 1414029760 10874560 7662352
01:17:19 38026 8192000000 10310152928 10760290304 1378913920 14.407 99 37 74 0 1399870800 13064992 7891888
01:19:23 80688 8192000000 10297475216 10753556480 1376331200 14.384 99 23 45 0 1393906176 11929280 5645696
01:21:44 51093 8192000000 10283478304 10746957824 1360763920 14.244 99 13 24 1 1377590160 13977472 2848768
01:23:36 81697 8192000000 10284922960 10759888896 1387039664 14.479 100 16 32 0 1407465120 16432704 3992752
01:26:34 102056 8192000000 10290868784 10757865472 1359108032 14.229 98 15 30 1 1374664432 11893872 3662528
01:29:16 78174 8192000000 10284202592 10743812096 1351800592 14.164 98 18 36 0 1370093488 13896160 4396736
01:31:34 73374 8192000000 10272182624 10735476736 1332252496 13.988 97 16 32 0 1349008656 12755568 4000592
01:34:01 56451 8192000000 10257782064 10711810048 1299283328 13.689 95 15 30 0 1324068832 20788320 3997184
01:35:59 57529 8192000000 10243265792 10690170880 1259837760 13.329 93 20 40 0 1277632768 13405056 4389952
01:37:57 44463 8192000000 10243901360 10677915648 1279103680 13.505 95 31 62 0 1300595712 14631520 6860512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-03-2023 01:39:55 to 11-03-2023 01:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-03-2023 01:40:12 to 11-03-2023 01:40:14.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          247527
MON$OLDEST_ACTIVE               247792
MON$OLDEST_SNAPSHOT             247792
MON$NEXT_TRANSACTION            247792
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-03 16:41:55.3470
MON$PAGES                       2049248
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:1313: 
:1314: mscs-test-161.budzdorov.ru    Sat Mar 11 01:39:00 2023
:1315:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1316: 
:1317: 
:1318: mscs-test-161.budzdorov.ru    Sat Mar 11 01:39:01 2023
:1319:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1320: 
--- end of diff output ---

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

11.03.23 01:40:15. There are 3 logs in /var/tmp/logs-oltp30 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
2023-03-11 01:40:17 No severe PSQL-related problems occured
11.03.23 01:40:19. End of report.