Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.11.2021 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 16925948 kB
MemAvailable 31546740 kB
Buffers 0 kB
Cached 14741592 kB
SwapCached 0 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.8
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 2021-11-24 19:37:56
DTS_END                         2021-11-24 19:37:56.9290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47900
TRN_ID                          231044
ATT_ID                          605
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: 9166.70 23424 1100004
customer order: creation 164.11 373 19693
customer order: refuse 13.91 361 1669
order to supplier: creation 43.75 1333 5250
order to supplier: removal 3.48 103026 417
invoice (draft): creation 43.22 3466 5186
invoice (draft): removal 54.18 811 6501
invoice accept: apply 39.08 18842 4690
invoice accept: cancel 48.37 842 5804
customer reserve: creation 3657.78 184 438933
customer reserve: removal 4808.50 58 577020
realization accept: apply 78.97 190 9477
realization accept: cancel 87.18 117 10461
payment from customer: creation 56.85 121 6822
payment from customer: removal 5.29 74 635
payment to supplier: creation 46.45 131 5574
payment to supplier: removal 3.60 81 432
service: total inventory turnovers 4.43 1120 531
service: total monetary turnovers 6.63 24 795
service: aggregate perf. data 0.92 16912 110
service: refresh index statistics 0.03 320408 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-11-2021 19:39:27 to 24-11-2021 19:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1209
TEST_TIME 2 3385
TEST_TIME 3 4211
TEST_TIME 4 3480
TEST_TIME 5 3726
TEST_TIME 6 3830
TEST_TIME 7 4108
TEST_TIME 8 4389
TEST_TIME 9 4682
TEST_TIME 10 5249
TEST_TIME 11 5462
TEST_TIME 12 5594
TEST_TIME 13 5894
TEST_TIME 14 6359
TEST_TIME 15 6437
TEST_TIME 16 6831
TEST_TIME 17 6786
TEST_TIME 18 7387
TEST_TIME 19 7918
TEST_TIME 20 8417
TEST_TIME 21 8846
TEST_TIME 22 8979
TEST_TIME 23 9381
TEST_TIME 24 9667
TEST_TIME 25 9796
TEST_TIME 26 9711
TEST_TIME 27 10110
TEST_TIME 28 10828
TEST_TIME 29 11018
TEST_TIME 30 10735
TEST_TIME 31 10410
TEST_TIME 32 10173
TEST_TIME 33 10118
TEST_TIME 34 10251
TEST_TIME 35 10104
TEST_TIME 36 10078
TEST_TIME 37 10080
TEST_TIME 38 9918
TEST_TIME 39 9829
TEST_TIME 40 9744
TEST_TIME 41 9673
TEST_TIME 42 9676
TEST_TIME 43 9555
TEST_TIME 44 9549
TEST_TIME 45 9452
TEST_TIME 46 9374
TEST_TIME 47 9339
TEST_TIME 48 9260
TEST_TIME 49 9227
TEST_TIME 50 9259
TEST_TIME 51 9201
TEST_TIME 52 9295
TEST_TIME 53 9313
TEST_TIME 54 9271
TEST_TIME 55 9321
TEST_TIME 56 9311
TEST_TIME 57 9405
TEST_TIME 58 9388
TEST_TIME 59 9351
TEST_TIME 60 9401
TEST_TIME 61 9386
TEST_TIME 62 9278
TEST_TIME 63 9265
TEST_TIME 64 9212
TEST_TIME 65 9171
TEST_TIME 66 9088
TEST_TIME 67 9073
TEST_TIME 68 9084
TEST_TIME 69 9112
TEST_TIME 70 9084
TEST_TIME 71 9068
TEST_TIME 72 9153
TEST_TIME 73 9111
TEST_TIME 74 9132
TEST_TIME 75 9149
TEST_TIME 76 9179
TEST_TIME 77 9214
TEST_TIME 78 9169
TEST_TIME 79 9242
TEST_TIME 80 9264
TEST_TIME 81 9277
TEST_TIME 82 9181
TEST_TIME 83 9159
TEST_TIME 84 9141
TEST_TIME 85 9157
TEST_TIME 86 9115
TEST_TIME 87 9062
TEST_TIME 88 9002
TEST_TIME 89 9001
TEST_TIME 90 9008
TEST_TIME 91 8967
TEST_TIME 92 8987
TEST_TIME 93 9040
TEST_TIME 94 9145
TEST_TIME 95 9136
TEST_TIME 96 9160
TEST_TIME 97 9128
TEST_TIME 98 9115
TEST_TIME 99 9145
TEST_TIME 100 9211
TEST_TIME 101 9224
TEST_TIME 102 9264
TEST_TIME 103 9298
TEST_TIME 104 9364
TEST_TIME 105 9339
TEST_TIME 106 9257
TEST_TIME 107 9235
TEST_TIME 108 9225
TEST_TIME 109 9184
TEST_TIME 110 9207
TEST_TIME 111 9194
TEST_TIME 112 9154
TEST_TIME 113 9114
TEST_TIME 114 9056
TEST_TIME 115 9137
TEST_TIME 116 9118
TEST_TIME 117 9144
TEST_TIME 118 9101
TEST_TIME 119 9123
TEST_TIME 120 9170

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-11-2021 19:39:30 to 24-11-2021 19:39:34.

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 1506428 1506428 0 0.00 0 16792 6 0 0
doc_list_biud 1506427 1506427 0 0.00 0 66067 29 0 0
sp_add_invoice_to_stock 4811 4690 121 2.51 35 226507 18843 0 121
sp_cancel_adding_invoice 5804 5804 0 0.00 5 61788 842 0 0
sp_cancel_client_order 1669 1669 0 0.00 4 13264 361 0 0
sp_cancel_customer_reserve 577020 577020 0 0.00 1 33687 58 0 0
sp_cancel_pay_from_customer 635 635 0 0.00 2 3786 75 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 7707 81 0 0
sp_cancel_supplier_invoice 6510 6501 9 0.13 2 66079 812 0 9
sp_cancel_supplier_order 417 417 0 0.00 6 994886 103026 0 0
sp_cancel_write_off 10461 10461 0 0.00 1 11674 118 0 0
sp_client_order 19693 19693 0 0.00 3 31630 374 0 0
sp_customer_reserve 438933 438933 0 0.00 0 84286 185 0 0
sp_fill_shopping_cart 30120 30120 0 0.00 1 10733 42 0 0
sp_fill_shopping_cart_clo_res 438927 438927 0 0.00 0 16172 16 0 0
sp_get_clo_for_invoice 4684 4684 0 0.00 0 37763 357 0 0
sp_kill_cost_storno 17332 17332 0 0.00 0 8629 59 0 0
sp_kill_qstorno_ret_qs2qd 1171419 1171419 0 0.00 0 66063 32 0 0
sp_kill_qty_storno 616033 616033 0 0.00 0 66065 69 0 0
sp_lock_selected_doc 19728 19728 0 0.00 0 7874 5 0 0
sp_make_cost_storno 26557 26557 0 0.00 0 13972 58 0 0
sp_multiply_rows_for_pdistr 26557 26557 0 0.00 0 8700 28 0 0
sp_multiply_rows_for_qdistr 19693 19693 0 0.00 0 31596 182 0 0
sp_pay_from_customer 6822 6822 0 0.00 4 13556 122 0 0
sp_pay_to_supplier 5574 5574 0 0.00 5 13551 131 0 0
sp_payment_common 12396 12396 0 0.00 1 8723 31 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 0 13256 310 0 0
sp_qd_handle_on_invoice_upd_sts 10488 10488 0 0.00 0 40166 364 0 0
sp_qd_handle_on_reserve_upd_sts 9477 9477 0 0.00 0 17491 65 0 0
sp_reserve_write_off 9477 9477 0 0.00 6 17515 190 0 0
sp_supplier_invoice 5186 5186 0 0.00 7 85704 3466 0 0
sp_supplier_order 5250 5250 0 0.00 5 56482 1333 0 0
srv_aggregate_perf_data 110 110 0 0.00 2972 245779 16912 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2393 171470 52924 0 0
srv_find_qd_qs_mism 1054504 1054504 0 0.00 0 16791 5 0 0
srv_make_invnt_saldo 531 531 0 0.00 54 31043 1121 0 0
srv_make_money_saldo 795 795 0 0.00 1 3037 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 130727 425763 320409 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 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 4691 4691 0 0.00 0 11353 155 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 6060 110 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 9232 25 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 2318 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 635 635 0 0.00 0 736 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 148 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 2855 16 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 33836 1739 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 1386 9 0 0
t$perf-norm:sp_client_order 19693 19693 0 0.00 0 10311 17 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 1053 6 0 0
t$perf-norm:sp_pay_from_customer 6822 6822 0 0.00 0 9682 11 0 0
t$perf-norm:sp_pay_to_supplier 5574 5574 0 0.00 0 11283 13 0 0
t$perf-norm:sp_reserve_write_off 9477 9477 0 0.00 0 7004 12 0 0
t$perf-norm:sp_supplier_invoice 5186 5186 0 0.00 0 8905 22 0 0
t$perf-norm:sp_supplier_order 5250 5250 0 0.00 0 3954 15 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 1726 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 59 1 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 4695 29 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 7593 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9610 9368 242 2.51 0 8355 21 0 242
v_all_customers 36574 36574 0 0.00 0 24 0 0 0
v_all_suppliers 29198 29198 0 0.00 0 17 1 0 0
v_all_wares 39386 39386 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 1134 8 0 0
v_cancel_client_order 3338 3338 0 0.00 0 9234 15 0 0
v_cancel_customer_prepayment 1270 1270 0 0.00 0 3690 9 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 508 4 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 238 3 0 0
v_cancel_supplier_invoice 778 760 18 2.31 0 3051 21 0 18
v_cancel_supplier_order 834 834 0 0.00 0 5561 14 0 0
v_cancel_write_off 1982 1982 0 0.00 0 8835 23 0 0
v_max_id_clo_ord 5250 5250 0 0.00 0 930 1 0 0
v_max_id_clo_res 960 960 0 0.00 0 1990 19 0 0
v_max_id_ord_sup 5177 5177 0 0.00 0 5780 6 0 0
v_max_non_paid_invoice 1402 1402 0 0.00 0 3327 7 0 0
v_max_non_paid_realizn 3342 3342 0 0.00 0 4151 6 0 0
v_min_id_clo_ord 5250 5250 0 0.00 0 4578 7 0 0
v_min_id_clo_res 960 960 0 0.00 0 1288 5 0 0
v_min_id_ord_sup 5177 5177 0 0.00 0 5751 7 0 0
v_min_non_paid_invoice 5574 5574 0 0.00 0 8691 23 0 0
v_min_non_paid_realizn 6822 6822 0 0.00 0 8712 24 0 0
v_our_firm 9772 9772 0 0.00 0 81 1 0 0
v_reserve_write_off 18954 18954 0 0.00 0 8581 9 0 0
x_lock_depdocs_on_canc_invoice 11925 11925 0 0.00 0 24085 125 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 3466 316 0 0
x_make_qstorno_1000_1200 5250 5250 0 0.00 2 56461 1258 0 0
x_make_qstorno_1200_2000 5186 5186 0 0.00 2 85694 3304 0 0
x_make_qstorno_2100_3300 438933 438933 0 0.00 0 84285 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-11-2021 19:39:35 to 24-11-2021 19:39:39.

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:00 2 8192000000 8801737504 8813907968 7763376 0.094 2 2 3 0 7904464 66144 74944
17:09:02 15529 8192000000 9892305264 10204721152 976986576 10.655 100 82 164 0 1004709728 5751936 21971216
17:10:23 19413 8192000000 9942263648 10264817664 1025120256 11.121 100 86 172 0 1055019088 7473216 22425616
17:11:56 17812 8192000000 9975798096 10306961408 1059327520 11.450 100 82 164 0 1088689600 8433664 20928416
17:13:19 14078 8192000000 10020812496 10373931008 1110947328 11.941 100 65 130 0 1135648400 8747264 15953808
17:14:41 10224 8192000000 10083814048 10451128320 1173415376 12.529 100 64 128 0 1198250000 10579168 14255456
17:15:55 4977 8192000000 10128563648 10505699328 1223187312 12.991 100 70 140 0 1250921472 11865216 15868944
17:17:02 4452 8192000000 10170042960 10547449856 1257256096 13.305 100 82 165 1 1289303024 13806512 18240416
17:18:09 2106 8192000000 10196866928 10575163392 1274123728 13.459 100 93 187 1 1309341312 14308768 20908816
17:19:13 2485 8192000000 10213011888 10598002688 1292703904 13.629 100 93 186 2 1326879456 12833456 21342096
17:20:16 19358 8192000000 10217583120 10609082368 1305307728 13.743 100 76 151 2 1330143616 8272544 16563344
17:21:36 4127 8192000000 10208561648 10620133376 1316220608 13.842 100 40 80 2 1333406416 8347744 8838064
17:22:45 9590 8192000000 10217971792 10631577600 1326964688 13.940 100 25 50 0 1338763168 6513120 5285360
17:23:57 6404 8192000000 10223853392 10643865600 1333146656 13.996 100 23 46 1 1345865200 7045040 5673504
17:25:10 39590 8192000000 10230181680 10652250112 1334688080 14.009 100 30 60 1 1347293632 5948256 6657296
17:26:53 8990 8192000000 10229357744 10655182848 1338991504 14.048 100 23 46 0 1350729840 6495744 5242592
17:28:02 6472 8192000000 10213302784 10632384512 1311165600 13.797 99 20 40 1 1322037168 6537088 4334480
17:29:10 9023 8192000000 10179539088 10597752832 1294808032 13.648 100 24 48 0 1306674320 6451440 5414848
17:30:19 29513 8192000000 10138797072 10540503040 1176815520 12.560 91 27 54 1 1188665680 5899328 5950832
17:31:52 5238 8192000000 10133409936 10529964032 1225792640 13.015 99 30 60 1 1239032128 6277184 6962304
17:32:59 5963 8192000000 10135016864 10532622336 1220837840 12.969 98 38 77 1 1237657488 7036384 9783264
17:34:07 17079 8192000000 10162287072 10569629696 1268313520 13.406 100 25 50 2 1283082624 9336256 5432848
17:35:56 10671 8192000000 10181707776 10590216192 1288976688 13.595 100 20 40 2 1299580624 5762064 4841872
17:37:10 10501 8192000000 10200757792 10609778688 1299870704 13.694 100 22 44 2 1310337152 5498128 4968320
17:38:31 31028 8192000000 10242249328 10643738624 1325226752 13.924 100 43 86 1 1343883440 9246896 9409792
17:40:05 21028 8192000000 10266013696 10679881728 1357758960 14.217 100 49 98 1 1381677872 13447088 10471824
17:41:36 126662 8192000000 10293979600 10720378880 1375908576 14.380 100 39 78 0 1405120048 19517104 9694368
17:44:47 16243 8192000000 10308894464 10731954176 1388969680 14.497 100 40 80 0 1418365488 20252528 9143280
17:46:08 3183 8192000000 10311300960 10730848256 1381579792 14.431 99 74 143 2 1420085488 21949488 16556208
17:47:15 7309 8192000000 10330324176 10750115840 1401677424 14.610 100 54 108 0 1438172416 24132960 12362032
17:48:27 6237 8192000000 10342315952 10761519104 1408161008 14.668 100 64 126 0 1448280064 25436096 14682960
17:49:37 5854 8192000000 10348695904 10767167488 1408625808 14.672 100 65 129 2 1453468384 29396960 15445616
17:50:48 5103 8192000000 10348730112 10767740928 1396796336 14.566 99 64 128 0 1446737360 33598992 16342032
17:51:59 2392 8192000000 10364699808 10781052928 1419782768 14.771 100 78 155 1 1471055072 32088112 19184192
17:53:07 4053 8192000000 10364568768 10777448448 1409080832 14.676 100 80 158 1 1466482112 36784320 20616960
17:54:14 3365 8192000000 10372212064 10794938368 1420490112 14.777 100 77 154 1 1481863216 40601200 20771904
17:55:21 15149 8192000000 10355720928 10785312768 1396663920 14.565 98 53 106 0 1445443088 34279008 14500160
17:56:46 3116 8192000000 10347200448 10761904128 1395264576 14.553 100 73 146 1 1444521424 30104688 19152160
17:57:54 17086 8192000000 10311933744 10745155584 1366537984 14.296 99 37 74 1 1411708000 35061520 10108496
17:59:16 11156 8192000000 10323712016 10752512000 1383436672 14.447 100 45 90 1 1432382768 36499728 12446368
18:00:34 10603 8192000000 10319814256 10754375680 1357133216 14.212 98 29 58 2 1406409488 41034256 8242016
18:01:59 27583 8192000000 10297615616 10741846016 1368808704 14.316 100 27 54 0 1410487360 34437664 7240992
18:03:56 24316 8192000000 10302197264 10755051520 1367980208 14.309 100 21 42 0 1412854720 38681296 6193216
18:05:22 42075 8192000000 10277015184 10734448640 1340926768 14.066 98 12 24 1 1379006512 34577296 3502448
18:07:04 118942 8192000000 10283865264 10721652736 1363487424 14.269 100 28 57 0 1383161904 13356192 6318288
18:10:04 34479 8192000000 10312309136 10744143872 1382493056 14.439 100 47 93 2 1413289008 20410720 10385232
18:11:42 75121 8192000000 10279933056 10735607808 1357674496 14.216 98 11 22 0 1376332848 16385616 2272736
18:14:07 43733 8192000000 10252373808 10701561856 1316534912 13.845 96 16 32 2 1328972240 8610592 3826736
18:15:54 63771 8192000000 10263574928 10717138944 1347293808 14.123 98 20 40 0 1366975632 14699104 4982720
18:18:54 34035 8192000000 10286799712 10729517056 1360036480 14.238 99 24 46 1 1374360608 8103216 6220912
18:20:31 85297 8192000000 10274504912 10741334016 1376880672 14.389 100 18 36 0 1394909376 13893344 4135360
18:22:56 91245 8192000000 10272548704 10742325248 1370591856 14.332 99 18 36 1 1384425488 9765968 4067664
18:25:39 43692 8192000000 10291307504 10747953152 1376939248 14.389 99 37 74 1 1393947200 6999328 10008624
18:27:24 48921 8192000000 10298988432 10754015232 1392620384 14.529 100 14 28 0 1403325824 7791232 2914208
18:29:13 55709 8192000000 10301076848 10754539520 1370343152 14.330 98 14 28 3 1382163264 8933776 2886336
18:31:09 121676 8192000000 10304301808 10772488192 1357230336 14.212 97 18 37 0 1380228592 18754016 4244240
18:34:14 62438 8192000000 10309228336 10765582336 1374073568 14.364 98 14 28 0 1388740112 11870560 2795984
18:36:17 41266 8192000000 10311192784 10757562368 1374793648 14.370 98 33 66 0 1393180544 11369952 7016944
18:38:08 85351 8192000000 10311325200 10771382272 1393801408 14.540 99 37 74 0 1418467040 16192560 8473072
18:40:37 59290 8192000000 10309369792 10773958656 1398864608 14.585 100 17 34 1 1416883296 14058688 3960000
18:42:37 63515 8192000000 10290938608 10758406144 1386788192 14.477 99 7 14 0 1397363568 9156032 1419344
18:44:41 40559 8192000000 10274143680 10734055424 1344604000 14.099 97 23 46 0 1363013920 13048352 5361568
18:46:22 73547 8192000000 10270954208 10716393472 1273344176 13.452 92 23 46 1 1287366928 8812688 5210064
18:48:46 44539 8192000000 10267676944 10722222080 1324605600 13.918 97 21 42 0 1343694832 14517536 4571696
18:50:31 73683 8192000000 10270094688 10726035456 1342984848 14.084 98 17 34 0 1357983632 11085600 3913184
18:52:47 87762 8192000000 10272603568 10738139136 1350248224 14.150 98 30 60 0 1369295344 12645200 6401920
18:55:16 49854 8192000000 10287602688 10734702592 1367383952 14.304 99 35 70 2 1389041488 13647680 8009856
18:57:09 71171 8192000000 10270006256 10712514560 1313737344 13.820 95 16 32 0 1332249744 14747008 3765392
18:59:29 142884 8192000000 10248749232 10697076736 1278244368 13.497 93 20 38 1 1297134640 14945776 3944496
19:02:53 42365 8192000000 10235480992 10695217152 1326363584 13.934 98 14 29 2 1336983616 7960736 2659296
19:04:37 48285 8192000000 10261306080 10714624000 1338790976 14.047 98 12 23 0 1352942336 11486848 2664512
19:06:32 66607 8192000000 10284458768 10757726208 1370191840 14.329 100 14 28 0 1394783664 21444960 3146864
19:09:10 66761 8192000000 10298061952 10748133376 1358960144 14.228 98 23 46 0 1386977840 22191824 5825872
19:11:24 93179 8192000000 10294124848 10746605568 1371663456 14.342 99 13 26 0 1384286288 9812640 2810192
19:13:58 37947 8192000000 10304521856 10751430656 1350347504 14.151 97 13 26 0 1362335168 9439920 2547744
19:15:38 68663 8192000000 10313174464 10775711744 1394343296 14.545 100 11 22 1 1416032096 19107680 2581120
19:17:47 76021 8192000000 10294551680 10746216448 1326139760 13.932 95 23 46 0 1348256160 17085680 5030720
19:20:03 171470 8192000000 10305800640 10773643264 1351865680 14.164 97 8 16 0 1368004288 14645232 1493376
19:23:56 75384 8192000000 10301524240 10768080896 1399797824 14.593 100 39 78 0 1418260704 9483520 8979360
19:26:29 83982 8192000000 10304537888 10772094976 1405379808 14.643 100 18 36 1 1421558320 12065600 4112912
19:28:58 56040 8192000000 10298507248 10757591040 1366354256 14.294 98 12 24 3 1381756704 12547392 2855056
19:30:54 70879 8192000000 10317192640 10778894336 1400565744 14.600 100 21 41 2 1430109936 23499920 6044272
19:33:05 30848 8192000000 10297943952 10761211904 1371607440 14.341 98 19 38 2 1386724128 10639872 4476816
19:34:41 71232 8192000000 10317412768 10773233664 1376524864 14.385 98 17 34 0 1398706640 18011648 4170128
19:36:59 72629 8192000000 9434065056 9759891456 573147456 6.538 42 4 8 0 589912320 15668512 1096352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-11-2021 19:39:41 to 24-11-2021 19:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 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 24-11-2021 19:39:56 to 24-11-2021 19:39:58.

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          231711
MON$OLDEST_ACTIVE               231712
MON$OLDEST_SNAPSHOT             231712
MON$NEXT_TRANSACTION            231712
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                       2011968
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:291: 
:292: mscs-test-161.budzdorov.ru    Wed Nov 24 19:38:14 2021
:293:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:294: 
:295: 
:296: mscs-test-161.budzdorov.ru    Wed Nov 24 19:38:15 2021
:297:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:298: 
:299: 
:300: mscs-test-161.budzdorov.ru    Wed Nov 24 19:38:15 2021
:301:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:302: 
--- end of diff output ---

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

24.11.21 19:39:59. 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
2021-11-24 19:40:02 No severe PSQL-related problems occured
24.11.21 19:40:03. End of report.