Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.05.2022 19: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 16322612 kB
MemAvailable 31496540 kB
Buffers 0 kB
Cached 15414380 kB
SwapCached 2632 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.10
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 2022-05-12 19:38:13
DTS_END                         2022-05-12 19:38:13.8780
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/55802
TRN_ID                          250024
ATT_ID                          694
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: 9554.59 19409 1146551
customer order: creation 182.71 364 21925
customer order: refuse 15.64 368 1877
order to supplier: creation 50.28 1337 6033
order to supplier: removal 3.43 109577 412
invoice (draft): creation 49.28 3383 5914
invoice (draft): removal 53.84 851 6461
invoice accept: apply 44.84 17125 5381
invoice accept: cancel 48.75 885 5850
customer reserve: creation 4031.39 172 483767
customer reserve: removal 4757.83 61 570939
realization accept: apply 90.13 199 10816
realization accept: cancel 89.83 118 10779
payment from customer: creation 62.36 120 7483
payment from customer: removal 6.19 79 743
payment to supplier: creation 51.36 142 6163
payment to supplier: removal 3.65 124 438
service: total inventory turnovers 5.03 1185 603
service: total monetary turnovers 7.15 27 858
service: aggregate perf. data 0.88 15268 105
service: refresh index statistics 0.03 236789 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-05-2022 19:39:45 to 12-05-2022 19:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6633
TEST_TIME 2 5406
TEST_TIME 3 4466
TEST_TIME 4 6229
TEST_TIME 5 6113
TEST_TIME 6 5848
TEST_TIME 7 5657
TEST_TIME 8 5814
TEST_TIME 9 6204
TEST_TIME 10 6161
TEST_TIME 11 6209
TEST_TIME 12 7014
TEST_TIME 13 7119
TEST_TIME 14 7254
TEST_TIME 15 7473
TEST_TIME 16 7491
TEST_TIME 17 7649
TEST_TIME 18 7613
TEST_TIME 19 7624
TEST_TIME 20 8569
TEST_TIME 21 9020
TEST_TIME 22 9184
TEST_TIME 23 9355
TEST_TIME 24 9395
TEST_TIME 25 9419
TEST_TIME 26 9436
TEST_TIME 27 9729
TEST_TIME 28 10089
TEST_TIME 29 10646
TEST_TIME 30 11422
TEST_TIME 31 11280
TEST_TIME 32 11140
TEST_TIME 33 11113
TEST_TIME 34 10986
TEST_TIME 35 10976
TEST_TIME 36 10757
TEST_TIME 37 10608
TEST_TIME 38 10564
TEST_TIME 39 10580
TEST_TIME 40 10476
TEST_TIME 41 10428
TEST_TIME 42 10278
TEST_TIME 43 10240
TEST_TIME 44 10189
TEST_TIME 45 10242
TEST_TIME 46 10199
TEST_TIME 47 10276
TEST_TIME 48 10199
TEST_TIME 49 10104
TEST_TIME 50 10093
TEST_TIME 51 10006
TEST_TIME 52 9905
TEST_TIME 53 10138
TEST_TIME 54 10067
TEST_TIME 55 10066
TEST_TIME 56 10027
TEST_TIME 57 10076
TEST_TIME 58 10086
TEST_TIME 59 10040
TEST_TIME 60 10162
TEST_TIME 61 10200
TEST_TIME 62 10200
TEST_TIME 63 10289
TEST_TIME 64 10292
TEST_TIME 65 10219
TEST_TIME 66 10217
TEST_TIME 67 10281
TEST_TIME 68 10292
TEST_TIME 69 10244
TEST_TIME 70 10127
TEST_TIME 71 10089
TEST_TIME 72 10076
TEST_TIME 73 10010
TEST_TIME 74 10034
TEST_TIME 75 9975
TEST_TIME 76 9920
TEST_TIME 77 9833
TEST_TIME 78 9824
TEST_TIME 79 9741
TEST_TIME 80 9752
TEST_TIME 81 9797
TEST_TIME 82 9821
TEST_TIME 83 9801
TEST_TIME 84 9814
TEST_TIME 85 9766
TEST_TIME 86 9778
TEST_TIME 87 9844
TEST_TIME 88 9815
TEST_TIME 89 9983
TEST_TIME 90 10016
TEST_TIME 91 10035
TEST_TIME 92 10011
TEST_TIME 93 10020
TEST_TIME 94 10021
TEST_TIME 95 9925
TEST_TIME 96 9886
TEST_TIME 97 9862
TEST_TIME 98 9809
TEST_TIME 99 9787
TEST_TIME 100 9763
TEST_TIME 101 9732
TEST_TIME 102 9705
TEST_TIME 103 9675
TEST_TIME 104 9646
TEST_TIME 105 9721
TEST_TIME 106 9761
TEST_TIME 107 9726
TEST_TIME 108 9753
TEST_TIME 109 9738
TEST_TIME 110 9722
TEST_TIME 111 9787
TEST_TIME 112 9779
TEST_TIME 113 9747
TEST_TIME 114 9731
TEST_TIME 115 9677
TEST_TIME 116 9640
TEST_TIME 117 9622
TEST_TIME 118 9645
TEST_TIME 119 9628
TEST_TIME 120 9561

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-05-2022 19:39:49 to 12-05-2022 19:39:52.

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 1594426 1594426 0 0.00 0 30633 6 0 0
doc_list_biud 1594425 1594425 0 0.00 0 37411 28 0 0
sp_add_invoice_to_stock 5524 5381 143 2.58 37 179367 17125 0 143
sp_cancel_adding_invoice 5850 5850 0 0.00 5 62878 885 0 0
sp_cancel_client_order 1877 1877 0 0.00 4 17581 369 0 0
sp_cancel_customer_reserve 570939 570939 0 0.00 1 37416 61 0 0
sp_cancel_pay_from_customer 743 743 0 0.00 3 7423 80 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 23456 124 0 0
sp_cancel_supplier_invoice 6473 6461 12 0.18 2 25574 851 0 12
sp_cancel_supplier_order 412 412 0 0.00 6 1067980 109577 0 0
sp_cancel_write_off 10779 10779 0 0.00 1 11556 119 0 0
sp_client_order 21925 21925 0 0.00 3 44587 364 0 0
sp_customer_reserve 483767 483767 0 0.00 0 89230 173 0 0
sp_fill_shopping_cart 33866 33866 0 0.00 1 12304 40 0 0
sp_fill_shopping_cart_clo_res 483759 483759 0 0.00 0 20263 13 0 0
sp_get_clo_for_invoice 5373 5373 0 0.00 0 24972 256 0 0
sp_kill_cost_storno 17810 17810 0 0.00 0 26798 60 0 0
sp_kill_qstorno_ret_qs2qd 1159530 1159530 0 0.00 0 37405 33 0 0
sp_kill_qty_storno 612507 612507 0 0.00 0 37410 73 0 0
sp_lock_selected_doc 22367 22367 0 0.00 0 7960 5 0 0
sp_make_cost_storno 29835 29835 0 0.00 0 30580 61 0 0
sp_multiply_rows_for_pdistr 29835 29835 0 0.00 0 7780 33 0 0
sp_multiply_rows_for_qdistr 21925 21925 0 0.00 0 44576 161 0 0
sp_pay_from_customer 7483 7483 0 0.00 4 12033 121 0 0
sp_pay_to_supplier 6163 6163 0 0.00 5 30942 142 0 0
sp_payment_common 13646 13646 0 0.00 2 10284 36 0 0
sp_qd_handle_on_cancel_clo 1877 1877 0 0.00 0 12727 332 0 0
sp_qd_handle_on_invoice_upd_sts 11223 11223 0 0.00 0 28252 361 0 0
sp_qd_handle_on_reserve_upd_sts 10816 10816 0 0.00 0 29537 63 0 0
sp_reserve_write_off 10816 10816 0 0.00 6 29575 199 0 0
sp_supplier_invoice 5914 5914 0 0.00 7 90856 3383 0 0
sp_supplier_order 6033 6033 0 0.00 6 42698 1338 0 0
srv_aggregate_perf_data 105 105 0 0.00 3262 126791 15269 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1518 115973 41528 0 0
srv_find_qd_qs_mism 1096057 1096057 0 0.00 0 18270 5 0 0
srv_make_invnt_saldo 603 603 0 0.00 26 56574 1186 0 0
srv_make_money_saldo 858 858 0 0.00 1 1633 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 114164 516869 236789 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 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 5381 5381 0 0.00 0 24536 155 0 0
t$perf-norm:sp_cancel_adding_invoice 464 464 0 0.00 0 7914 98 0 0
t$perf-norm:sp_cancel_client_order 1877 1877 0 0.00 0 2739 12 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 386 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 743 743 0 0.00 0 2254 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 252 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 2181 19 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 27166 1768 0 0
t$perf-norm:sp_cancel_write_off 1098 1098 0 0.00 0 8170 17 0 0
t$perf-norm:sp_client_order 21925 21925 0 0.00 0 4957 16 0 0
t$perf-norm:sp_customer_reserve 1087 1087 0 0.00 0 1095 6 0 0
t$perf-norm:sp_pay_from_customer 7483 7483 0 0.00 0 5024 8 0 0
t$perf-norm:sp_pay_to_supplier 6163 6163 0 0.00 0 4818 10 0 0
t$perf-norm:sp_reserve_write_off 10816 10816 0 0.00 0 8265 9 0 0
t$perf-norm:sp_supplier_invoice 5914 5914 0 0.00 0 5049 18 0 0
t$perf-norm:sp_supplier_order 6033 6033 0 0.00 0 9994 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 229 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 1096 23 0 0
t$perf-norm:srv_make_money_saldo 858 858 0 0.00 0 2443 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 264 72 0 0
v_add_invoice_to_stock 11026 10740 286 2.59 0 17323 24 0 286
v_all_customers 39650 39650 0 0.00 0 18 1 0 0
v_all_suppliers 32734 32734 0 0.00 0 26 1 0 0
v_all_wares 43850 43850 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 928 928 0 0.00 0 9591 27 0 0
v_cancel_client_order 3754 3754 0 0.00 0 17352 10 0 0
v_cancel_customer_prepayment 1486 1486 0 0.00 0 6095 7 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1683 5 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 23437 34 0 0
v_cancel_supplier_invoice 902 878 24 2.66 0 2192 19 0 24
v_cancel_supplier_order 824 824 0 0.00 0 902 5 0 0
v_cancel_write_off 2196 2196 0 0.00 0 4313 16 0 0
v_max_id_clo_ord 6033 6033 0 0.00 0 7946 3 0 0
v_max_id_clo_res 1087 1087 0 0.00 0 3091 16 0 0
v_max_id_ord_sup 5908 5908 0 0.00 0 5180 5 0 0
v_max_non_paid_invoice 1737 1737 0 0.00 0 3009 6 0 0
v_max_non_paid_realizn 4098 4098 0 0.00 0 2562 4 0 0
v_min_id_clo_ord 6033 6033 0 0.00 0 6537 12 0 0
v_min_id_clo_res 1087 1087 0 0.00 0 6959 19 0 0
v_min_id_ord_sup 5908 5908 0 0.00 0 6265 6 0 0
v_min_non_paid_invoice 6163 6163 0 0.00 0 8922 22 0 0
v_min_non_paid_realizn 7483 7483 0 0.00 0 9041 30 0 0
v_our_firm 10970 10970 0 0.00 0 16 1 0 0
v_reserve_write_off 21632 21632 0 0.00 0 9256 9 0 0
x_lock_depdocs_on_canc_invoice 11872 11872 0 0.00 0 16391 131 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 6408 371 0 0
x_make_qstorno_1000_1200 6033 6033 0 0.00 2 42688 1244 0 0
x_make_qstorno_1200_2000 5914 5914 0 0.00 3 86616 3250 0 0
x_make_qstorno_2100_3300 483767 483767 0 0.00 0 89230 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-05-2022 19:39:53 to 12-05-2022 19:39:57.

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
17:08:17 21 8192000000 8803859088 8816791552 9197120 0.112 3 2 3 0 9340096 68032 74944
17:09:18 28560 8192000000 9902531424 10211631104 979333984 10.678 100 90 180 0 1009051600 6383968 23333648
17:10:52 21622 8192000000 9944562240 10264915968 1023132752 11.102 100 87 174 0 1053893088 8050880 22709456
17:12:18 15899 8192000000 9969656208 10301759488 1059414288 11.451 100 79 158 2 1088550096 9616528 19519280
17:13:38 12751 8192000000 10035341408 10380226560 1118263600 12.011 100 79 158 0 1148325456 11590512 18471344
17:14:54 8382 8192000000 10068878304 10429988864 1157756080 12.382 100 79 158 0 1188952240 12743264 18452896
17:16:12 5754 8192000000 10120147440 10487525376 1206882144 12.840 100 82 164 0 1239322768 13581584 18859040
17:17:20 2920 8192000000 10159357376 10532687872 1248289424 13.222 100 79 158 0 1279018384 12698224 18030736
17:18:24 1959 8192000000 10189150640 10568769536 1276357056 13.480 100 91 180 2 1311416880 13720528 21339296
17:19:27 5442 8192000000 10209415632 10593222656 1296290000 13.661 100 74 145 1 1323071424 10568432 16212992
17:20:34 41398 8192000000 10215290144 10616737792 1311811584 13.803 100 51 102 0 1330123360 7032208 11279568
17:22:16 8657 8192000000 10207893376 10630651904 1325118016 13.923 100 16 32 0 1334941904 6308000 3515888
17:23:30 10066 8192000000 10219954368 10637807616 1330957152 13.976 100 13 26 0 1339509744 5675776 2876816
17:24:42 77018 8192000000 10220777888 10646061056 1335114688 14.013 100 33 66 1 1349796656 5849024 8832944
17:26:59 6300 8192000000 10228598720 10651758592 1339523488 14.053 100 20 40 0 1350641968 6569728 4548752
17:28:07 7925 8192000000 10209631824 10631041024 1301458896 13.709 98 17 33 0 1315412704 9606352 4347456
17:29:15 16039 8192000000 10190943488 10597154816 1268384576 13.407 97 23 47 1 1279013760 5140224 5488960
17:30:32 19017 8192000000 10146885776 10554499072 1156498112 12.370 90 14 29 2 1164344992 4810080 3036800
17:31:54 3798 8192000000 10130014064 10528935936 1221721712 12.978 98 19 38 0 1235463312 9376576 4365024
17:33:00 6935 8192000000 10158310736 10559881216 1243755792 13.181 98 27 54 0 1255549152 5107696 6685664
17:34:08 54290 8192000000 10180686176 10584788992 1283503280 13.545 100 29 55 4 1295937776 5452016 6982480
17:36:04 7627 8192000000 10185669536 10597961728 1283987616 13.549 99 26 52 1 1296299680 6507072 5804992
17:37:13 9975 8192000000 10186140768 10605809664 1300629392 13.701 100 25 49 1 1311532784 5228896 5674496
17:38:23 21656 8192000000 10235500224 10646065152 1331793264 13.983 100 33 66 1 1344239728 5413376 7033088
17:39:50 39754 8192000000 10260211760 10672865280 1336837504 14.029 99 43 86 1 1357546336 10844448 9864384
17:41:34 73599 8192000000 10273818144 10704850944 1364935168 14.282 100 38 76 2 1391767184 17092512 9739504
17:43:58 33003 8192000000 10289112752 10717245440 1377425408 14.394 100 33 66 3 1400787184 15731728 7630048
17:45:41 10000 8192000000 10315389440 10736336896 1388950608 14.497 100 61 122 3 1426175872 22907392 14317872
17:47:04 6032 8192000000 10327871424 10747531264 1398966064 14.586 100 58 114 3 1436538064 24212752 13359248
17:48:16 5452 8192000000 10330219312 10751025152 1392810720 14.531 99 68 136 0 1434559376 25667840 16080816
17:49:25 12121 8192000000 10357664144 10779635712 1419347600 14.767 100 70 140 0 1465301120 29043824 16909696
17:50:47 2865 8192000000 10333710832 10744070144 1387699360 14.485 99 83 162 2 1435352464 27794720 19858384
17:51:55 4024 8192000000 10342481680 10760527872 1395663920 14.556 99 67 134 0 1442188240 30372096 16152224
17:53:06 1515 8192000000 10334411824 10739646464 1385232144 14.463 100 73 146 2 1435687632 32325248 18130240
17:54:12 2907 8192000000 10346682592 10755149824 1386580032 14.475 99 89 178 3 1443267264 34438736 22248496
17:55:19 2429 8192000000 10344482400 10753662976 1385354784 14.464 100 69 136 0 1439449984 37454544 16640656
17:56:28 3559 8192000000 10340651920 10738688000 1363990368 14.273 99 81 162 0 1428582336 44231408 20360560
17:57:37 4361 8192000000 10365308096 10771582976 1387277872 14.482 100 74 148 0 1464322544 57535968 19508704
17:58:48 5881 8192000000 10352434480 10765418496 1380530112 14.421 99 62 124 0 1445119040 48596864 15992064
18:00:03 7336 8192000000 10346299520 10766323712 1393584592 14.538 100 52 103 2 1451045072 43926688 13533792
18:01:21 3595 8192000000 10334351424 10749677568 1377895376 14.398 100 56 111 1 1436739520 44695088 14149056
18:02:28 19001 8192000000 10320244400 10750377984 1360453616 14.241 99 27 54 0 1413325776 45224192 7647968
18:04:01 23940 8192000000 10318200384 10743877632 1338529024 14.044 98 31 63 0 1405323552 57686080 9108448
18:05:49 26813 8192000000 10328449568 10763837440 1364236000 14.275 100 28 56 0 1432192544 59562400 8394144
18:07:40 37295 8192000000 10293977216 10744377344 1341558624 14.071 99 12 25 0 1392409152 47637232 3213296
18:09:18 67537 8192000000 10284723904 10715901952 1349986736 14.147 99 16 32 0 1365633760 11717392 3929632
18:11:32 53994 8192000000 10272996032 10713849856 1362077520 14.256 100 15 30 0 1374226784 9195136 2954128
18:13:28 80046 8192000000 10300970352 10741424128 1379883456 14.416 100 13 27 4 1393120720 9728912 3508352
18:15:48 87558 8192000000 10313585248 10747772928 1380075440 14.417 99 45 90 1 1403297808 13326320 9896048
18:18:17 36291 8192000000 10315716896 10750320640 1380582768 14.422 99 44 86 0 1397353200 7054768 9715664
18:19:57 50572 8192000000 10313746592 10759671808 1395743744 14.557 100 32 65 1 1414374000 10924112 7706144
18:21:50 32679 8192000000 10307571824 10758250496 1396605872 14.565 100 18 36 1 1413818512 12984848 4227792
18:23:31 10370 8192000000 10311759120 10747461632 1367262480 14.303 98 59 115 1 1399907456 17831376 14813600
18:24:48 16955 8192000000 10314168656 10768859136 1402681088 14.619 100 33 65 0 1426214624 16035040 7498496
18:26:18 46710 8192000000 10297437216 10761015296 1383291344 14.446 99 16 32 0 1401382576 14146928 3944304
18:28:17 66593 8192000000 10302025728 10763272192 1367126496 14.301 98 11 22 0 1388745488 19014624 2604368
18:30:23 70648 8192000000 10304142160 10774118400 1397492144 14.573 100 17 34 1 1420883520 19611344 3780032
18:32:35 56607 8192000000 10286132096 10739630080 1346467072 14.116 97 16 32 1 1363657728 13595568 3595088
18:34:34 56746 8192000000 10286172160 10750935040 1377673968 14.396 100 16 32 1 1391854448 10478848 3701632
18:36:36 67159 8192000000 10272340512 10727391232 1310043776 13.786 94 13 26 0 1327460000 14299824 3116400
18:38:56 73986 8192000000 10295579952 10734731264 1314859120 13.830 95 18 36 0 1327930352 8794064 4277168
18:41:14 48885 8192000000 10281547792 10733969408 1324177760 13.915 96 22 43 1 1351590656 22356512 5056384
18:43:04 33362 8192000000 10286739104 10720071680 1324295328 13.916 96 35 68 2 1339403776 6603920 8504528
18:44:38 68672 8192000000 10264075888 10717175808 1279393072 13.507 94 30 60 0 1300540496 14490272 6657152
18:46:49 25730 8192000000 10262311824 10700648448 1335512800 14.017 99 30 60 0 1351967232 9915296 6539136
18:48:22 115972 8192000000 10259513968 10719723520 1350276496 14.150 99 24 48 0 1364314480 8525872 5512112
18:51:26 3162 8192000000 10256789840 10689187840 1325899360 13.930 98 40 77 0 1343074624 8166560 9008704
18:52:30 50471 8192000000 10246360512 10699431936 1316444256 13.845 97 13 26 0 1325757392 6557392 2755744
18:54:20 31642 8192000000 10252753200 10699558912 1348663216 14.135 100 17 34 1 1361314672 8345728 4305728
18:55:52 54926 8192000000 10276967264 10730659840 1361594768 14.252 100 24 48 1 1386312480 18543232 6174480
18:57:47 22147 8192000000 10273926192 10730618880 1369114928 14.319 100 17 34 1 1388808208 14781216 4912064
18:59:21 46848 8192000000 10272008928 10726535168 1345208560 14.104 98 16 31 2 1359535824 10507888 3819376
19:01:09 61175 8192000000 10283977440 10742312960 1384353200 14.455 100 20 40 3 1399051408 10396352 4301856
19:03:14 39039 8192000000 10325475216 10766913536 1388498608 14.492 99 28 55 0 1410111264 15330688 6281968
19:05:15 81545 8192000000 10319266608 10779639808 1398139008 14.578 100 17 34 0 1423962672 21865696 3957968
19:07:38 109052 8192000000 10292473776 10748506112 1307440656 13.763 94 8 16 0 1326931312 17855216 1635440
19:10:27 69190 8192000000 10303940528 10749374464 1345148368 14.104 96 17 34 0 1361833328 13378096 3306864
19:13:37 56274 8192000000 10279019408 10729807872 1354310000 14.186 98 18 36 0 1364986176 6638032 4038144
19:15:35 51588 8192000000 10280051392 10749046784 1380387376 14.420 100 3 6 0 1388410416 7716192 306848
19:17:27 36573 8192000000 10287833936 10740981760 1369715280 14.324 99 23 46 0 1380914160 6443264 4755616
19:19:08 39003 8192000000 10313781872 10754723840 1384469136 14.456 100 35 70 0 1401972512 9863680 7639696
19:20:48 45716 8192000000 10276450864 10729582592 1339548032 14.053 97 20 40 1 1355528224 11429712 4550480
19:22:46 66753 8192000000 10253187536 10707972096 1306788144 13.757 95 22 44 0 1328613600 16605040 5220416
19:25:05 76050 8192000000 10248647680 10699755520 1278324704 13.498 94 12 24 0 1290225552 9042960 2857888
19:27:28 36370 8192000000 10251915344 10679980032 1284739440 13.556 95 30 60 0 1299302560 7918464 6644656
19:29:15 107570 8192000000 10264822016 10706591744 1273619072 13.455 94 15 30 0 1287308464 10474544 3214848
19:32:03 87791 8192000000 10242747216 10694434816 1286894096 13.576 96 14 28 0 1298310080 7770896 3645088
19:34:38 49634 8192000000 10256099632 10704523264 1325860576 13.930 98 24 46 0 1340857184 9889312 5107296
19:36:40 28321 8192000000 10241258384 10713264128 1343702624 14.091 100 3 6 0 1357811616 13541872 567120
19:38:09 17163 8192000000 8916069408 9084133376 95436576 1.151 8 1 2 0 96145568 666640 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-05-2022 19:39:59 to 12-05-2022 19:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 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 12-05-2022 19:40:15 to 12-05-2022 19:40:17.

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          250780
MON$OLDEST_ACTIVE               250781
MON$OLDEST_SNAPSHOT             250781
MON$NEXT_TRANSACTION            250781
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                       2057248
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:219: 
:220: mscs-test-161.budzdorov.ru    Thu May 12 19:38:27 2022
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:222: 
--- end of diff output ---

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

12.05.22 19:40:18. 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
2022-05-12 19:40:20 No severe PSQL-related problems occured
12.05.22 19:40:22. End of report.