Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.10.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 15424308 kB
MemAvailable 30489044 kB
Buffers 0 kB
Cached 16255692 kB
SwapCached 2144 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-10-10 22:38:25
DTS_END                         2022-10-10 22:38:25.7450
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/39874
TRN_ID                          249173
ATT_ID                          674
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: 9337.93 18909 1120552
customer order: creation 182.33 340 21880
customer order: refuse 14.82 333 1778
order to supplier: creation 47.77 1191 5732
order to supplier: removal 3.46 96410 415
invoice (draft): creation 47.73 3347 5728
invoice (draft): removal 50.63 831 6075
invoice accept: apply 44.23 16527 5307
invoice accept: cancel 46.56 881 5587
customer reserve: creation 4002.73 167 480327
customer reserve: removal 4590.07 55 550808
realization accept: apply 88.07 192 10568
realization accept: cancel 82.44 100 9893
payment from customer: creation 62.67 139 7520
payment from customer: removal 5.97 100 716
payment to supplier: creation 51.57 153 6188
payment to supplier: removal 3.67 105 440
service: total inventory turnovers 5.24 999 629
service: total monetary turnovers 7.13 41 856
service: aggregate perf. data 0.84 21780 101
service: refresh index statistics 0.03 234481 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-10-2022 22:39:36 to 10-10-2022 22:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5107
TEST_TIME 2 4174
TEST_TIME 3 4265
TEST_TIME 4 5205
TEST_TIME 5 5425
TEST_TIME 6 5080
TEST_TIME 7 5039
TEST_TIME 8 5269
TEST_TIME 9 5476
TEST_TIME 10 5582
TEST_TIME 11 5821
TEST_TIME 12 5715
TEST_TIME 13 5570
TEST_TIME 14 5714
TEST_TIME 15 6130
TEST_TIME 16 6076
TEST_TIME 17 6335
TEST_TIME 18 6530
TEST_TIME 19 6492
TEST_TIME 20 6957
TEST_TIME 21 7110
TEST_TIME 22 8210
TEST_TIME 23 8423
TEST_TIME 24 8906
TEST_TIME 25 9358
TEST_TIME 26 10026
TEST_TIME 27 9885
TEST_TIME 28 9984
TEST_TIME 29 9954
TEST_TIME 30 10161
TEST_TIME 31 10096
TEST_TIME 32 10009
TEST_TIME 33 10164
TEST_TIME 34 10057
TEST_TIME 35 9998
TEST_TIME 36 9930
TEST_TIME 37 9913
TEST_TIME 38 9929
TEST_TIME 39 9856
TEST_TIME 40 9891
TEST_TIME 41 9832
TEST_TIME 42 9895
TEST_TIME 43 9881
TEST_TIME 44 9899
TEST_TIME 45 9960
TEST_TIME 46 9831
TEST_TIME 47 9659
TEST_TIME 48 9803
TEST_TIME 49 9673
TEST_TIME 50 9644
TEST_TIME 51 9648
TEST_TIME 52 9543
TEST_TIME 53 9560
TEST_TIME 54 9623
TEST_TIME 55 9570
TEST_TIME 56 9594
TEST_TIME 57 9497
TEST_TIME 58 9365
TEST_TIME 59 9354
TEST_TIME 60 9425
TEST_TIME 61 9378
TEST_TIME 62 9416
TEST_TIME 63 9442
TEST_TIME 64 9447
TEST_TIME 65 9514
TEST_TIME 66 9498
TEST_TIME 67 9579
TEST_TIME 68 9661
TEST_TIME 69 9695
TEST_TIME 70 9714
TEST_TIME 71 9730
TEST_TIME 72 9723
TEST_TIME 73 9810
TEST_TIME 74 9759
TEST_TIME 75 9678
TEST_TIME 76 9623
TEST_TIME 77 9672
TEST_TIME 78 9580
TEST_TIME 79 9525
TEST_TIME 80 9521
TEST_TIME 81 9523
TEST_TIME 82 9466
TEST_TIME 83 9411
TEST_TIME 84 9397
TEST_TIME 85 9374
TEST_TIME 86 9419
TEST_TIME 87 9412
TEST_TIME 88 9390
TEST_TIME 89 9388
TEST_TIME 90 9421
TEST_TIME 91 9435
TEST_TIME 92 9473
TEST_TIME 93 9472
TEST_TIME 94 9510
TEST_TIME 95 9575
TEST_TIME 96 9528
TEST_TIME 97 9572
TEST_TIME 98 9598
TEST_TIME 99 9595
TEST_TIME 100 9620
TEST_TIME 101 9618
TEST_TIME 102 9642
TEST_TIME 103 9582
TEST_TIME 104 9537
TEST_TIME 105 9530
TEST_TIME 106 9480
TEST_TIME 107 9455
TEST_TIME 108 9414
TEST_TIME 109 9453
TEST_TIME 110 9418
TEST_TIME 111 9385
TEST_TIME 112 9372
TEST_TIME 113 9395
TEST_TIME 114 9407
TEST_TIME 115 9372
TEST_TIME 116 9394
TEST_TIME 117 9391
TEST_TIME 118 9409
TEST_TIME 119 9390
TEST_TIME 120 9339

Performance per minute, chart


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

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 1567576 1567576 0 0.00 0 35333 6 0 0
doc_list_biud 1567575 1567575 0 0.00 0 76788 25 0 0
sp_add_invoice_to_stock 5520 5307 213 3.85 63 174436 16527 0 213
sp_cancel_adding_invoice 5587 5587 0 0.00 4 68587 882 0 0
sp_cancel_client_order 1778 1778 0 0.00 5 12898 333 0 0
sp_cancel_customer_reserve 550808 550808 0 0.00 0 20367 56 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 8226 101 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 8245 106 0 0
sp_cancel_supplier_invoice 6091 6075 16 0.26 1 76800 832 0 16
sp_cancel_supplier_order 415 415 0 0.00 6 1038813 96410 0 0
sp_cancel_write_off 9893 9893 0 0.00 1 8592 101 0 0
sp_client_order 21880 21880 0 0.00 3 20296 341 0 0
sp_customer_reserve 480327 480327 0 0.00 0 77747 167 0 0
sp_fill_shopping_cart 33322 33322 0 0.00 1 32723 37 0 0
sp_fill_shopping_cart_clo_res 480323 480323 0 0.00 0 22577 12 0 0
sp_get_clo_for_invoice 5303 5303 0 0.00 0 20049 248 0 0
sp_kill_cost_storno 16636 16636 0 0.00 0 6006 54 0 0
sp_kill_qstorno_ret_qs2qd 1117999 1117999 0 0.00 0 76782 30 0 0
sp_kill_qty_storno 590427 590427 0 0.00 0 76785 66 0 0
sp_lock_selected_doc 21796 21796 0 0.00 0 8389 6 0 0
sp_make_cost_storno 29579 29579 0 0.00 0 34788 70 0 0
sp_multiply_rows_for_pdistr 29579 29579 0 0.00 0 12928 30 0 0
sp_multiply_rows_for_qdistr 21880 21880 0 0.00 0 13324 143 0 0
sp_pay_from_customer 7520 7520 0 0.00 4 30479 140 0 0
sp_pay_to_supplier 6188 6188 0 0.00 5 45495 154 0 0
sp_payment_common 13708 13708 0 0.00 1 45401 34 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 12891 305 0 0
sp_qd_handle_on_invoice_upd_sts 10890 10890 0 0.00 0 24954 334 0 0
sp_qd_handle_on_reserve_upd_sts 10568 10568 0 0.00 0 15503 56 0 0
sp_reserve_write_off 10568 10568 0 0.00 6 34318 192 0 0
sp_supplier_invoice 5728 5728 0 0.00 7 86639 3348 0 0
sp_supplier_order 5732 5732 0 0.00 5 25073 1192 0 0
srv_aggregate_perf_data 101 101 0 0.00 2971 222267 21781 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1068 133439 42840 0 0
srv_find_qd_qs_mism 1071578 1071578 0 0.00 0 21930 5 0 0
srv_make_invnt_saldo 629 629 0 0.00 44 67686 999 0 0
srv_make_money_saldo 856 856 0 0.00 1 8426 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 160226 368385 234482 0 0
t$perf-abend:sp_add_invoice_to_stock 213 213 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5307 5307 0 0.00 0 13007 148 0 0
t$perf-norm:sp_cancel_adding_invoice 466 466 0 0.00 0 5028 91 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 11700 21 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 1460 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 270 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 912 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 1580 11 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 22296 1682 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 2187 7 0 0
t$perf-norm:sp_client_order 21880 21880 0 0.00 0 9147 16 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 857 4 0 0
t$perf-norm:sp_pay_from_customer 7520 7520 0 0.00 0 4166 7 0 0
t$perf-norm:sp_pay_to_supplier 6188 6188 0 0.00 0 8340 12 0 0
t$perf-norm:sp_reserve_write_off 10568 10568 0 0.00 0 15571 14 0 0
t$perf-norm:sp_supplier_invoice 5728 5728 0 0.00 0 4147 17 0 0
t$perf-norm:sp_supplier_order 5732 5732 0 0.00 0 5066 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 217 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 69 1 0 0
t$perf-norm:srv_make_invnt_saldo 629 629 0 0.00 0 1529 20 0 0
t$perf-norm:srv_make_money_saldo 856 856 0 0.00 0 1980 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 11032 10606 426 3.86 0 5490 20 0 426
v_all_customers 39624 39624 0 0.00 0 27 1 0 0
v_all_suppliers 31498 31498 0 0.00 0 26 1 0 0
v_all_wares 43760 43760 0 0.00 0 151 1 0 0
v_cancel_adding_invoice 932 932 0 0.00 0 523 6 0 0
v_cancel_client_order 3556 3556 0 0.00 0 2999 4 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 4752 9 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 504 3 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 2297 8 0 0
v_cancel_supplier_invoice 814 782 32 3.93 0 3263 26 0 32
v_cancel_supplier_order 830 830 0 0.00 0 4846 12 0 0
v_cancel_write_off 2022 2022 0 0.00 0 4874 20 0 0
v_max_id_clo_ord 5732 5732 0 0.00 0 1377 2 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 5045 21 0 0
v_max_id_ord_sup 5710 5710 0 0.00 0 8118 7 0 0
v_max_non_paid_invoice 1881 1881 0 0.00 0 2297 3 0 0
v_max_non_paid_realizn 4024 4024 0 0.00 0 745 4 0 0
v_min_id_clo_ord 5732 5732 0 0.00 0 5089 8 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 9405 13 0 0
v_min_id_ord_sup 5710 5710 0 0.00 0 6204 11 0 0
v_min_non_paid_invoice 6188 6188 0 0.00 0 45360 26 0 0
v_min_non_paid_realizn 7520 7520 0 0.00 0 9585 25 0 0
v_our_firm 11128 11128 0 0.00 0 27 1 0 0
v_reserve_write_off 21136 21136 0 0.00 0 4849 7 0 0
x_lock_depdocs_on_canc_invoice 11271 11271 0 0.00 0 29111 131 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 9674 372 0 0
x_make_qstorno_1000_1200 5732 5732 0 0.00 1 25063 1117 0 0
x_make_qstorno_1200_2000 5728 5728 0 0.00 4 86622 3209 0 0
x_make_qstorno_2100_3300 480327 480327 0 0.00 0 77747 152 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-10-2022 22:39:44 to 10-10-2022 22:39:49.

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:29 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:30 28980 8192000000 9925915632 10241761280 1005161120 10.929 100 90 180 0 1034494448 6309024 23024304
20:11:04 21419 8192000000 9954867248 10278821888 1037842384 11.244 100 92 184 0 1070603984 9057088 23704512
20:12:39 15316 8192000000 10001274800 10338201600 1077464080 11.623 100 78 156 0 1106774064 10162560 19147424
20:13:57 9842 8192000000 10042782496 10395504640 1130845872 12.129 100 72 144 0 1158563248 10924528 16792848
20:15:13 5879 8192000000 10090966112 10451394560 1178383328 12.575 100 85 170 0 1211614160 13523856 19706976
20:16:20 3193 8192000000 10127299520 10496655360 1215437520 12.919 100 81 159 0 1246840480 13650928 17752032
20:17:25 1232 8192000000 10166371568 10542108672 1255335840 13.287 100 85 165 1 1285811712 12492192 17983680
20:18:28 1332 8192000000 10201088448 10585346048 1290058416 13.605 100 85 167 0 1322072800 13098624 18915760
20:19:31 1598 8192000000 10226999040 10616029184 1316916656 13.849 100 87 174 1 1350477648 13526160 20034832
20:20:34 9680 8192000000 10197196416 10616430592 1311178848 13.797 100 16 33 0 1320206864 5500896 3527120
20:21:48 7458 8192000000 10211708400 10630381568 1320305104 13.879 100 20 40 2 1335134608 10361392 4468112
20:22:58 7957 8192000000 10209585936 10630852608 1325268704 13.924 100 21 40 1 1336490416 6559920 4661792
20:24:06 10088 8192000000 10214786688 10635862016 1328840016 13.957 100 24 48 0 1340868528 6440160 5588352
20:25:23 21643 8192000000 10205359968 10630115328 1319655808 13.874 99 36 73 0 1333769824 4983552 9130464
20:26:46 5649 8192000000 10234279920 10654093312 1336618096 14.027 100 37 76 0 1352480256 7020864 8841296
20:27:52 7829 8192000000 10217296848 10633506816 1312686960 13.810 99 27 54 0 1324836208 5913088 6236160
20:29:01 13551 8192000000 10159847888 10565238784 1206008240 12.832 92 25 49 0 1216967600 4793984 6165376
20:30:15 12842 8192000000 10172308848 10578960384 1253826992 13.273 97 15 29 0 1261900192 4824944 3248256
20:31:29 4479 8192000000 10141012032 10534510592 1227151840 13.028 98 37 74 0 1242319200 7158960 8008400
20:32:34 5537 8192000000 10159548560 10562056192 1263848288 13.365 100 20 40 0 1273641632 5283808 4509536
20:33:41 5655 8192000000 10170203136 10575876096 1277766608 13.493 100 26 50 0 1289091216 5303264 6021344
20:34:49 25768 8192000000 10175335664 10587308032 1273225584 13.451 99 24 48 1 1284467888 5379008 5863296
20:36:17 6764 8192000000 10189565856 10601836544 1296996400 13.668 100 27 52 0 1309978784 6632320 6350064
20:37:29 6607 8192000000 10204184880 10612502528 1305963392 13.749 100 18 37 1 1315530784 5602032 3965360
20:38:39 11386 8192000000 10235950368 10644844544 1330921984 13.975 100 37 74 0 1344865248 4504160 9439104
20:39:58 21776 8192000000 10258993856 10667941888 1347012832 14.121 100 51 103 1 1367135216 8079280 12043104
20:41:55 14348 8192000000 10274021280 10687827968 1359230032 14.230 100 49 96 0 1380538672 10961200 10347440
20:43:13 10772 8192000000 10282494160 10699579392 1372734880 14.352 100 50 98 0 1394819968 10697616 11387472
20:44:27 18020 8192000000 10286470240 10713128960 1381050720 14.426 100 31 62 0 1402329104 14317872 6960512
20:45:49 5845 8192000000 10308322608 10729918464 1391773344 14.522 100 44 86 2 1418875520 16199520 10902656
20:47:03 7598 8192000000 10324093120 10748067840 1403855600 14.629 100 56 112 1 1438123536 20935392 13332544
20:48:15 16585 8192000000 10327588464 10747887616 1386777504 14.477 98 60 118 1 1422372944 21137152 14458288
20:49:36 2528 8192000000 10347304272 10764038144 1405627136 14.645 100 66 130 1 1456530880 34981664 15922080
20:50:55 8217 8192000000 10341909680 10766028800 1409784688 14.682 100 43 86 1 1455649408 33576560 12288160
20:52:20 8775 8192000000 10342207056 10762162176 1402102160 14.614 100 48 96 0 1451171728 36774016 12295552
20:53:36 2980 8192000000 10350161728 10772504576 1410352848 14.687 100 47 94 0 1461485776 39140320 11992608
20:54:46 2896 8192000000 10354019104 10766708736 1399948256 14.595 100 67 133 0 1457208560 40608992 16651312
20:55:55 1066 8192000000 10358266192 10760175616 1393244704 14.535 100 89 176 1 1459559504 44698464 21616336
20:57:03 4575 8192000000 10333394624 10732056576 1366910368 14.299 100 76 152 1 1434654816 47856512 19887936
20:58:13 1385 8192000000 10328075856 10724474880 1355085600 14.193 100 82 164 0 1433951216 56727968 22137648
20:59:20 3088 8192000000 10359506304 10761633792 1379665344 14.414 100 84 168 1 1461931184 60073072 22192768
21:00:33 5351 8192000000 10363883216 10786131968 1393845856 14.540 100 55 110 0 1473567728 64817296 14904576
21:01:42 23893 8192000000 10325811328 10758455296 1367088896 14.301 99 35 70 0 1422751936 45333456 10329584
21:03:08 34913 8192000000 10295739296 10729963520 1308534416 13.773 96 25 50 0 1365567696 50038848 6994432
21:04:47 47616 8192000000 10267384272 10698584064 1321683104 13.892 97 19 40 1 1349972416 23603568 4685744
21:06:57 26996 8192000000 10259854112 10700083200 1338059728 14.040 99 20 38 0 1361931072 19070624 4800720
21:08:32 19387 8192000000 10275799376 10730184704 1361749936 14.253 100 8 16 0 1386504464 22817712 1936816
21:09:52 54920 8192000000 10278346272 10714374144 1341576032 14.072 98 43 85 1 1362166256 10748256 9841968
21:12:28 56231 8192000000 10277501632 10721923072 1360614096 14.243 99 8 16 0 1371390912 9056528 1720288
21:14:26 69025 8192000000 10291242592 10726715392 1341944224 14.075 97 11 22 0 1357921968 13764016 2213728
21:16:51 41586 8192000000 10317372576 10748801024 1385292368 14.464 100 35 70 0 1409048576 15297872 8458336
21:18:39 67187 8192000000 10283525968 10728235008 1352650496 14.171 97 8 16 0 1362093200 7707104 1735600
21:21:11 124764 8192000000 10309725760 10769412096 1342427184 14.079 96 8 16 0 1365274464 21127424 1719856
21:24:17 57760 8192000000 10306456800 10757963776 1400314880 14.598 100 13 26 0 1411122896 7549728 3258288
21:26:32 72377 8192000000 10316323280 10762002432 1372947536 14.353 98 38 76 0 1393278832 12076384 8254912
21:28:56 29628 8192000000 10311555248 10769215488 1403242224 14.624 100 23 46 0 1423899280 14762928 5894128
21:30:28 18094 8192000000 10311662880 10750726144 1392354000 14.527 100 56 109 2 1416632544 11885104 12393440
21:32:13 44104 8192000000 10270395600 10729971712 1351316896 14.159 97 14 28 0 1371778480 16936768 3524816
21:34:03 133439 8192000000 10264143232 10721247232 1346443488 14.115 97 15 30 1 1355891296 6255824 3191984
21:37:24 46155 8192000000 10273512832 10732212224 1327492336 13.944 96 18 36 0 1343861488 11878560 4490592
21:39:19 51225 8192000000 10251252384 10699431936 1298702336 13.683 95 27 55 0 1310648640 6050080 5896224
21:41:17 26553 8192000000 10281176160 10719334400 1367016608 14.300 100 25 48 0 1380899040 8252240 5630192
21:42:47 35339 8192000000 10261380384 10708000768 1320964416 13.885 97 22 44 1 1340629520 14463536 5201568
21:44:22 31017 8192000000 10242582544 10672848896 1295227664 13.652 97 34 64 0 1311814976 9173200 7414112
21:45:54 46632 8192000000 10262282960 10714947584 1346145488 14.113 100 7 14 1 1361701216 13959424 1596304
21:47:43 110882 8192000000 10272120880 10732544000 1326567696 13.936 97 16 32 0 1344741728 14654896 3519136
21:50:34 55029 8192000000 10275966144 10727919616 1349159808 14.140 99 9 18 0 1367670384 16426144 2084432
21:52:34 73589 8192000000 10271553056 10710294528 1357104048 14.211 99 47 92 0 1375596112 7582304 10909760
21:54:53 66586 8192000000 10261072368 10718490624 1321947904 13.894 96 23 46 0 1337693296 10477152 5268240
21:57:18 27642 8192000000 10275719776 10719473664 1367796976 14.307 100 19 38 0 1381305744 9116128 4392640
21:58:47 62755 8192000000 10271139824 10733096960 1357195440 14.212 99 14 28 0 1376226496 15801360 3229696
22:00:50 64344 8192000000 10288244976 10743922688 1369357856 14.321 99 16 33 0 1384339296 11255264 3726176
22:03:09 51499 8192000000 10278270784 10734039040 1339877952 14.056 97 10 20 0 1353009472 11018496 2113024
22:05:06 77418 8192000000 10276101888 10734080000 1334284144 14.006 97 14 28 0 1356237248 19084688 2868416
22:07:29 61022 8192000000 10287590032 10745901056 1358939888 14.228 98 22 42 0 1373560688 9885424 4735376
22:09:40 62849 8192000000 10294908672 10746863616 1378864000 14.406 99 17 34 0 1390955536 8409264 3682272
22:11:43 85739 8192000000 10308490704 10763046912 1382876896 14.442 99 8 16 0 1397280672 12754336 1649440
22:14:33 39602 8192000000 10313172288 10755276800 1382712512 14.441 99 38 76 1 1403949072 12834528 8402032
22:16:15 62496 8192000000 10267499760 10718830592 1276191488 13.478 92 15 28 1 1291361888 12520400 2650000
22:18:18 65440 8192000000 10283888336 10726699008 1327259856 13.942 96 22 44 1 1342242048 9938832 5043360
22:20:29 118081 8192000000 10278683520 10724225024 1303793456 13.730 94 18 36 0 1317635472 9854352 3987664
22:23:42 37511 8192000000 10264558240 10724626432 1356160528 14.203 99 12 24 1 1368315264 9575840 2578896
22:25:19 50242 8192000000 10271322080 10730885120 1355914688 14.201 99 17 32 0 1377976544 18414656 3647200
22:27:10 41271 8192000000 10250931776 10713059328 1320675504 13.883 97 11 22 0 1336585840 13122928 2787408
22:28:52 66051 8192000000 10257005872 10702655488 1322293248 13.897 98 23 46 1 1341652208 13917168 5441792
22:31:24 61620 8192000000 10240774352 10691268608 1301203440 13.706 96 15 31 0 1318828048 14275984 3348624
22:33:26 25060 8192000000 10256161664 10687782912 1315899344 13.840 98 41 82 0 1334848976 9760736 9188896
22:34:56 51999 8192000000 10247950704 10691932160 1316202832 13.842 98 23 46 0 1332863264 11227488 5432944
22:36:49 59786 8192000000 10288668032 10729422848 1367774384 14.307 100 28 54 0 1388465488 14864976 5826128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-10-2022 22:39:51 to 10-10-2022 22:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 213 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

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

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          249172
MON$OLDEST_ACTIVE               251143
MON$OLDEST_SNAPSHOT             251143
MON$NEXT_TRANSACTION            251143
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                       2036832
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.33634 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33634 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33634 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33634 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.10.22 22:40:10. 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-10-10 22:40:12 No severe PSQL-related problems occured
10.10.22 22:40:14. End of report.