Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.08.2022 22: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 16048112 kB
MemAvailable 30956296 kB
Buffers 0 kB
Cached 15697564 kB
SwapCached 2508 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 2022-08-10 22:38:24
DTS_END                         2022-08-10 22:38:24.6210
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/35838
TRN_ID                          239505
ATT_ID                          659
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: 8921.98 24261 1070637
customer order: creation 169.19 381 20303
customer order: refuse 14.92 374 1790
order to supplier: creation 44.83 1302 5379
order to supplier: removal 3.56 99139 427
invoice (draft): creation 46.38 3450 5566
invoice (draft): removal 50.90 869 6108
invoice accept: apply 39.58 19095 4750
invoice accept: cancel 45.63 881 5476
customer reserve: creation 3708.43 186 445011
customer reserve: removal 4521.00 60 542520
realization accept: apply 82.31 199 9877
realization accept: cancel 66.45 123 7974
payment from customer: creation 58.47 124 7016
payment from customer: removal 5.80 95 696
payment to supplier: creation 48.57 146 5828
payment to supplier: removal 3.63 70 435
service: total inventory turnovers 4.70 1224 564
service: total monetary turnovers 6.73 29 808
service: aggregate perf. data 0.88 18704 105
service: refresh index statistics 0.03 338775 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-08-2022 22:40:03 to 10-08-2022 22:40:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4389
TEST_TIME 2 3322
TEST_TIME 3 4877
TEST_TIME 4 4854
TEST_TIME 5 4916
TEST_TIME 6 5432
TEST_TIME 7 6626
TEST_TIME 8 6628
TEST_TIME 9 6563
TEST_TIME 10 6524
TEST_TIME 11 6707
TEST_TIME 12 6574
TEST_TIME 13 6826
TEST_TIME 14 7001
TEST_TIME 15 7311
TEST_TIME 16 7709
TEST_TIME 17 8025
TEST_TIME 18 8321
TEST_TIME 19 8391
TEST_TIME 20 8435
TEST_TIME 21 8252
TEST_TIME 22 8646
TEST_TIME 23 8386
TEST_TIME 24 8930
TEST_TIME 25 8780
TEST_TIME 26 8824
TEST_TIME 27 9258
TEST_TIME 28 10037
TEST_TIME 29 10919
TEST_TIME 30 10724
TEST_TIME 31 10632
TEST_TIME 32 10428
TEST_TIME 33 10379
TEST_TIME 34 10380
TEST_TIME 35 10232
TEST_TIME 36 10181
TEST_TIME 37 10035
TEST_TIME 38 9930
TEST_TIME 39 9753
TEST_TIME 40 9665
TEST_TIME 41 9631
TEST_TIME 42 9612
TEST_TIME 43 9580
TEST_TIME 44 9397
TEST_TIME 45 9378
TEST_TIME 46 9295
TEST_TIME 47 9277
TEST_TIME 48 9316
TEST_TIME 49 9222
TEST_TIME 50 9094
TEST_TIME 51 9024
TEST_TIME 52 8941
TEST_TIME 53 8927
TEST_TIME 54 8846
TEST_TIME 55 8958
TEST_TIME 56 8958
TEST_TIME 57 8924
TEST_TIME 58 8920
TEST_TIME 59 9062
TEST_TIME 60 9123
TEST_TIME 61 9156
TEST_TIME 62 9106
TEST_TIME 63 9241
TEST_TIME 64 9220
TEST_TIME 65 9278
TEST_TIME 66 9279
TEST_TIME 67 9287
TEST_TIME 68 9380
TEST_TIME 69 9274
TEST_TIME 70 9244
TEST_TIME 71 9241
TEST_TIME 72 9295
TEST_TIME 73 9269
TEST_TIME 74 9290
TEST_TIME 75 9221
TEST_TIME 76 9183
TEST_TIME 77 9135
TEST_TIME 78 9034
TEST_TIME 79 9043
TEST_TIME 80 9100
TEST_TIME 81 9095
TEST_TIME 82 9105
TEST_TIME 83 9103
TEST_TIME 84 9056
TEST_TIME 85 9153
TEST_TIME 86 9135
TEST_TIME 87 9135
TEST_TIME 88 9222
TEST_TIME 89 9204
TEST_TIME 90 9197
TEST_TIME 91 9189
TEST_TIME 92 9282
TEST_TIME 93 9194
TEST_TIME 94 9121
TEST_TIME 95 9097
TEST_TIME 96 9054
TEST_TIME 97 9048
TEST_TIME 98 9075
TEST_TIME 99 9037
TEST_TIME 100 9002
TEST_TIME 101 9058
TEST_TIME 102 9047
TEST_TIME 103 9072
TEST_TIME 104 9162
TEST_TIME 105 9164
TEST_TIME 106 9170
TEST_TIME 107 9187
TEST_TIME 108 9216
TEST_TIME 109 9222
TEST_TIME 110 9184
TEST_TIME 111 9170
TEST_TIME 112 9120
TEST_TIME 113 9096
TEST_TIME 114 9087
TEST_TIME 115 9042
TEST_TIME 116 9043
TEST_TIME 117 9018
TEST_TIME 118 9013
TEST_TIME 119 8980
TEST_TIME 120 8926

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-08-2022 22:40:06 to 10-08-2022 22:40:10.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1483786 1483786 0 0.00 0 30597 7 0 0
doc_list_biud 1483780 1483780 0 0.00 0 39070 28 0 0
sp_add_invoice_to_stock 4854 4750 104 2.14 54 197549 19096 0 104
sp_cancel_adding_invoice 5476 5476 0 0.00 5 64669 881 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 17608 374 0 0
sp_cancel_customer_reserve 542520 542520 0 0.00 0 27187 60 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 5760 96 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 2166 71 0 0
sp_cancel_supplier_invoice 6119 6108 11 0.17 1 25606 869 0 11
sp_cancel_supplier_order 427 427 0 0.00 9 1076285 99140 0 0
sp_cancel_write_off 7974 7974 0 0.00 1 15889 124 0 0
sp_client_order 20303 20303 0 0.00 3 26111 382 0 0
sp_customer_reserve 445011 445011 0 0.00 0 106711 186 0 0
sp_fill_shopping_cart 31240 31240 0 0.00 1 20227 44 0 0
sp_fill_shopping_cart_clo_res 445004 445004 0 0.00 0 20720 15 0 0
sp_get_clo_for_invoice 4740 4740 0 0.00 0 31072 354 0 0
sp_kill_cost_storno 14581 14581 0 0.00 0 22705 66 0 0
sp_kill_qstorno_ret_qs2qd 1099549 1099549 0 0.00 0 27176 33 0 0
sp_kill_qty_storno 578912 578912 0 0.00 0 39069 72 0 0
sp_lock_selected_doc 20390 20390 0 0.00 0 8006 5 0 0
sp_make_cost_storno 27461 27461 0 0.00 0 30595 54 0 0
sp_multiply_rows_for_pdistr 27461 27461 0 0.00 0 14821 31 0 0
sp_multiply_rows_for_qdistr 20303 20303 0 0.00 0 12993 143 0 0
sp_pay_from_customer 7016 7016 0 0.00 4 22278 125 0 0
sp_pay_to_supplier 5828 5828 0 0.00 5 30604 146 0 0
sp_payment_common 12844 12844 0 0.00 1 17645 28 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 7170 333 0 0
sp_qd_handle_on_invoice_upd_sts 10216 10216 0 0.00 0 39066 363 0 0
sp_qd_handle_on_reserve_upd_sts 9877 9877 0 0.00 0 16421 73 0 0
sp_reserve_write_off 9877 9877 0 0.00 6 23818 200 0 0
sp_supplier_invoice 5566 5566 0 0.00 5 89248 3451 0 0
sp_supplier_order 5379 5379 0 0.00 5 38387 1303 0 0
srv_aggregate_perf_data 105 105 0 0.00 2608 279740 18704 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2146 132202 44936 0 0
srv_find_qd_qs_mism 1024365 1024365 0 0.00 0 20772 6 0 0
srv_make_invnt_saldo 564 564 0 0.00 33 55882 1224 0 0
srv_make_money_saldo 808 808 0 0.00 1 1283 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 136356 570155 338775 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4750 4750 0 0.00 0 13642 186 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 2257 88 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 7825 20 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 2292 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 689 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1997 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 877 18 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 20991 1678 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 2801 15 0 0
t$perf-norm:sp_client_order 20303 20303 0 0.00 0 8074 17 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 2311 11 0 0
t$perf-norm:sp_pay_from_customer 7016 7016 0 0.00 0 19766 15 0 0
t$perf-norm:sp_pay_to_supplier 5828 5828 0 0.00 0 4093 13 0 0
t$perf-norm:sp_reserve_write_off 9877 9877 0 0.00 0 6815 13 0 0
t$perf-norm:sp_supplier_invoice 5566 5566 0 0.00 0 6065 22 0 0
t$perf-norm:sp_supplier_order 5379 5379 0 0.00 0 9842 19 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 3241 38 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 182 7 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 3836 35 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1062 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9688 9480 208 2.14 0 4962 20 0 208
v_all_customers 36884 36884 0 0.00 0 131 1 0 0
v_all_suppliers 30426 30426 0 0.00 0 18 1 0 0
v_all_wares 40606 40606 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 465 6 0 0
v_cancel_client_order 3580 3580 0 0.00 0 2380 5 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 3407 8 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 1735 5 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 457 4 0 0
v_cancel_supplier_invoice 770 748 22 2.85 0 2473 24 0 22
v_cancel_supplier_order 854 854 0 0.00 0 1947 7 0 0
v_cancel_write_off 2002 2002 0 0.00 0 2538 16 0 0
v_max_id_clo_ord 5374 5374 0 0.00 0 1998 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 3522 16 0 0
v_max_id_ord_sup 5562 5562 0 0.00 0 4366 4 0 0
v_max_non_paid_invoice 1551 1551 0 0.00 0 132 3 0 0
v_max_non_paid_realizn 3952 3952 0 0.00 0 2083 4 0 0
v_min_id_clo_ord 5374 5374 0 0.00 0 3645 8 0 0
v_min_id_clo_res 989 989 0 0.00 0 1815 8 0 0
v_min_id_ord_sup 5562 5562 0 0.00 0 7718 7 0 0
v_min_non_paid_invoice 5828 5828 0 0.00 0 8046 20 0 0
v_min_non_paid_realizn 7016 7016 0 0.00 0 10867 20 0 0
v_our_firm 9850 9850 0 0.00 0 19 1 0 0
v_reserve_write_off 19754 19754 0 0.00 0 3407 6 0 0
x_lock_depdocs_on_canc_invoice 11210 11210 0 0.00 0 30551 141 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 12807 366 0 0
x_make_qstorno_1000_1200 5379 5379 0 0.00 2 38333 1220 0 0
x_make_qstorno_1200_2000 5566 5566 0 0.00 2 89238 3291 0 0
x_make_qstorno_2100_3300 445011 445011 0 0.00 0 106696 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-08-2022 22:40:11 to 10-08-2022 22:40:15.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:28 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:29 25800 8192000000 9890012672 10197979136 973105248 10.617 100 87 174 0 1003096016 6405424 23585344
20:11:02 22747 8192000000 9931345136 10250084352 1013672336 11.011 100 85 170 0 1044376960 8239792 22464832
20:12:28 11216 8192000000 9960836992 10288648192 1046125808 11.324 100 87 174 0 1077645696 9112624 22407264
20:13:49 9228 8192000000 10002825328 10345783296 1090561008 11.748 100 75 150 0 1118824496 9652784 18610704
20:15:10 5910 8192000000 10066150416 10422722560 1158704768 12.391 100 76 153 1 1187097744 11321856 17071120
20:16:21 5292 8192000000 10116973520 10484871168 1205503392 12.827 100 84 167 0 1237641760 13685872 18452496
20:17:29 1758 8192000000 10155026416 10524471296 1237956144 13.127 100 94 188 0 1272860464 14246896 20657424
20:18:33 2938 8192000000 10173648016 10553155584 1262635440 13.354 100 79 156 0 1292913584 13543168 16734976
20:19:37 1467 8192000000 10214068320 10601390080 1299911936 13.694 100 90 176 0 1330689120 11614832 19162352
20:20:40 7683 8192000000 10212070656 10610704384 1306098784 13.751 100 61 124 0 1328299856 7939776 14261296
20:21:50 6737 8192000000 10198060752 10612289536 1311861008 13.803 100 33 67 1 1326725456 7192160 7672288
20:22:58 7855 8192000000 10216556288 10632241152 1327816672 13.947 100 26 52 0 1340547344 7068560 5662112
20:24:07 9496 8192000000 10228551008 10645696512 1334634032 14.009 100 18 34 0 1348423760 10334256 3455472
20:25:19 54031 8192000000 10240397600 10655350784 1343312368 14.087 100 41 82 0 1366184672 13483968 9388336
20:27:13 4458 8192000000 10234426992 10656722944 1343256656 14.087 100 35 69 1 1357318848 6135792 7926400
20:28:18 4981 8192000000 10220892608 10637275136 1310447792 13.790 98 21 43 0 1320797168 5605904 4743472
20:29:24 41558 8192000000 10163902720 10572038144 1169713856 12.494 89 33 65 0 1182228464 4888368 7626240
20:31:06 9723 8192000000 10157015392 10560180224 1253995664 13.275 99 17 35 1 1263499296 5674032 3829600
20:32:20 7460 8192000000 10145434544 10549231616 1247967824 13.220 99 18 31 0 1256327472 5092432 3267216
20:33:28 43971 8192000000 10168147568 10570133504 1237415616 13.122 97 35 68 0 1251558704 4787456 9355632
20:35:20 9136 8192000000 10184345312 10592628736 1287448784 13.581 100 24 48 0 1299279232 6255424 5575024
20:36:30 6649 8192000000 10185672800 10599530496 1297936560 13.676 100 23 47 1 1309288320 5779952 5571808
20:37:39 6799 8192000000 10203565392 10616479744 1306177488 13.751 100 33 66 2 1324302736 10586480 7538768
20:38:52 31157 8192000000 10237583392 10644697088 1333557952 13.999 100 58 116 0 1354729216 6938624 14232640
20:40:28 30945 8192000000 10251023280 10662350848 1342509856 14.080 100 47 91 1 1361191872 8958960 9723056
20:42:05 13861 8192000000 10254085680 10674814976 1353721552 14.181 100 42 83 1 1374853616 11720064 9412000
20:43:23 7818 8192000000 10275942000 10690539520 1360571952 14.242 100 56 112 0 1387824256 14114368 13137936
20:44:42 5950 8192000000 10305415440 10716680192 1377618576 14.395 100 65 128 0 1409908928 17042016 15248336
20:45:52 2928 8192000000 10306429248 10727411712 1390779440 14.513 100 60 120 0 1418870800 13335408 14755952
20:47:00 2164 8192000000 10312019952 10729279488 1390835600 14.513 100 65 120 1 1419534192 14458896 14239696
20:48:06 6554 8192000000 10317253232 10744287232 1404406144 14.634 100 51 101 0 1432738032 16406640 11925248
20:49:16 8589 8192000000 10316702176 10733191168 1392948560 14.532 100 61 122 1 1429560096 21779792 14831744
20:50:36 2143 8192000000 10334239216 10750783488 1400174784 14.597 100 61 120 0 1440653744 25966544 14512416
20:51:46 5565 8192000000 10326715712 10743123968 1392061056 14.524 100 63 125 0 1434919200 27265600 15592544
20:52:58 4061 8192000000 10333813200 10744557568 1386580032 14.475 100 66 128 0 1434916112 31984912 16351168
20:54:06 3776 8192000000 10345537984 10755911680 1391594304 14.520 100 72 142 0 1446635232 37236208 17804720
20:55:14 2221 8192000000 10334018144 10745417728 1376601152 14.386 100 86 171 0 1438165136 40229216 21334768
20:56:22 3464 8192000000 10342002432 10754125824 1387111104 14.480 100 78 155 1 1448769184 41803872 19854208
20:57:40 2951 8192000000 10312008176 10721083392 1355835504 14.200 98 70 137 0 1413120032 39747952 17536576
20:58:51 4132 8192000000 10337739328 10748358656 1374213600 14.365 99 76 151 4 1437931184 44729936 18987648
21:00:06 2911 8192000000 10364179232 10771456000 1393385360 14.536 100 84 163 0 1463181216 49815664 19980192
21:01:13 14518 8192000000 10353108064 10781089792 1396885440 14.567 100 41 81 0 1458025424 49889520 11250464
21:02:30 25314 8192000000 10356008992 10796244992 1403502224 14.626 100 31 62 0 1459321808 47255408 8564176
21:03:57 23172 8192000000 10341276816 10774716416 1355818848 14.200 98 25 50 0 1428905312 65293936 7792528
21:05:36 15373 8192000000 10330693152 10771738624 1351947312 14.165 99 30 56 1 1426392624 65830352 8614960
21:06:57 65589 8192000000 10283408576 10730094592 1362367168 14.259 100 18 36 0 1412940960 46116352 4457440
21:09:09 45813 8192000000 10291731824 10731343872 1373528464 14.359 99 45 88 3 1392651488 8182144 10940880
21:11:19 61287 8192000000 10274160400 10731741184 1379182080 14.409 100 24 48 0 1395303664 10755296 5366288
21:13:32 97053 8192000000 10273835136 10726498304 1326177072 13.933 96 15 30 0 1339558848 9916160 3465616
21:16:09 81987 8192000000 10284334416 10737360896 1372119280 14.346 99 19 39 0 1386814816 9908720 4786816
21:19:09 52182 8192000000 10300110976 10748837888 1391863424 14.522 100 34 67 0 1408600768 8492256 8245088
21:21:06 76909 8192000000 10286645440 10747576320 1390370240 14.509 100 13 26 0 1403487616 10020368 3097008
21:23:41 25808 8192000000 10297994032 10752176128 1400190656 14.597 100 27 53 1 1416807184 10308736 6307792
21:25:08 29101 8192000000 10332055616 10772643840 1408099264 14.667 100 48 96 0 1429446784 10329920 11017600
21:26:42 44595 8192000000 10290504768 10760695808 1401741696 14.611 100 11 22 0 1411453440 7174336 2537408
21:29:09 62330 8192000000 10297954656 10767155200 1408191536 14.668 100 18 36 0 1419724112 7908176 3624400
21:31:12 61547 8192000000 10306075760 10775769088 1387903440 14.487 98 14 28 0 1401677840 10998160 2776240
21:33:15 67365 8192000000 10288886656 10758901760 1361240416 14.248 97 12 24 0 1374945520 11305648 2399456
21:35:28 13846 8192000000 10306991792 10753769472 1387715472 14.485 99 32 65 0 1402560864 7672944 7172448
21:36:42 56054 8192000000 10289832896 10750922752 1353555136 14.179 97 14 26 0 1371664112 15238896 2870080
21:38:38 72160 8192000000 10277629936 10732384256 1319164320 13.869 95 19 38 0 1333865056 10582704 4118032
21:41:08 48643 8192000000 10272071808 10730971136 1321253136 13.888 96 16 33 0 1339076064 14173024 3649904
21:43:00 82920 8192000000 10241663760 10684051456 1233076704 13.082 91 8 16 0 1247229712 12391072 1761936
21:45:41 88949 8192000000 10252090768 10699378688 1290479632 13.609 96 11 22 0 1308070288 15326320 2264336
21:48:14 92354 8192000000 10250174304 10698924032 1339007600 14.048 100 24 48 0 1359128016 13863776 6256640
21:50:58 14182 8192000000 10242471680 10683621376 1292398768 13.626 96 23 44 0 1311700208 14604240 4697200
21:52:13 52718 8192000000 10236384784 10695614464 1339520864 14.053 100 7 14 0 1352522128 11432400 1568864
21:54:09 76096 8192000000 10254427024 10704371712 1311058400 13.796 97 26 53 0 1333243248 16259648 5925200
21:56:26 54623 8192000000 10252500032 10707308544 1336098000 14.022 99 11 22 1 1363923808 25211136 2614672
21:58:21 102271 8192000000 10249293616 10705489920 1297429680 13.672 96 9 16 0 1307120112 8011760 1678672
22:01:08 25625 8192000000 10260829600 10706845696 1348546736 14.134 99 33 66 2 1365168096 8456656 8164704
22:02:43 41082 8192000000 10280931552 10722603008 1345254448 14.105 99 41 81 1 1367620624 13076608 9289568
22:05:08 58752 8192000000 10288382528 10732802048 1368782448 14.316 100 39 76 1 1396636080 19674432 8179200
22:07:16 82179 8192000000 10290539024 10734424064 1346521472 14.116 98 28 55 0 1364060272 11576592 5962208
22:09:39 56583 8192000000 10300851584 10760863744 1385705376 14.468 100 10 20 0 1398962320 11372384 1884560
22:11:37 101866 8192000000 10283826592 10738696192 1374856800 14.371 99 30 61 2 1389207584 7286880 7063904
22:14:20 36373 8192000000 10274288624 10734845952 1349440352 14.142 97 5 10 0 1358761200 8148048 1172800
22:15:58 78801 8192000000 10280784640 10738577408 1347324896 14.123 98 10 18 1 1360513328 11157552 2030880
22:18:17 63649 8192000000 10301040192 10758856704 1373412768 14.358 98 19 38 0 1390039728 11829904 4797056
22:20:21 40614 8192000000 10300141488 10752229376 1370316096 14.330 99 23 44 0 1389600128 13158336 6125696
22:22:16 25383 8192000000 10299053120 10753941504 1374848688 14.370 99 23 46 0 1392412960 12226960 5337312
22:24:00 132201 8192000000 10294295888 10761203712 1328547904 13.954 95 8 16 0 1350569792 19912720 2109168
22:27:13 60181 8192000000 10319588944 10774638592 1383511952 14.448 99 16 32 0 1401967904 14881312 3574640
22:29:45 83819 8192000000 10290834432 10754068480 1362189776 14.257 97 36 72 0 1382131456 11598832 8342848
22:34:16 83673 8192000000 10261449344 10715136000 1316192192 13.842 96 10 20 0 1327062592 8455616 2414784
22:36:40 114078 8192000000 9827852816 10223276032 938720480 10.280 69 8 16 0 949199808 8903376 1575952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 10-08-2022 22:40:17 to 10-08-2022 22:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-08-2022 22:40:32 to 10-08-2022 22:40:34.

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          239395
MON$OLDEST_ACTIVE               239944
MON$OLDEST_SNAPSHOT             239944
MON$NEXT_TRANSACTION            239944
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                       2020672
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
--- end of diff output ---

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

10.08.22 22:40:35. 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-08-10 22:40:38 No severe PSQL-related problems occured
10.08.22 22:40:39. End of report.