Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.02.2023 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15051028 kB
MemAvailable 30418800 kB
Buffers 0 kB
Cached 16637864 kB
SwapCached 3140 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-02-20 01:38:39
DTS_END                         2023-02-20 01:38:39.6050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38376
TRN_ID                          245809
ATT_ID                          725
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: 9832.36 17562 1179883
customer order: creation 178.17 325 21380
customer order: refuse 14.30 352 1716
order to supplier: creation 47.65 1317 5718
order to supplier: removal 3.62 93348 434
invoice (draft): creation 47.78 3050 5733
invoice (draft): removal 56.78 729 6814
invoice accept: apply 43.82 15981 5258
invoice accept: cancel 51.28 694 6153
customer reserve: creation 4081.09 157 489731
customer reserve: removal 5000.42 51 600050
realization accept: apply 85.24 159 10229
realization accept: cancel 88.81 104 10657
payment from customer: creation 60.74 105 7289
payment from customer: removal 5.97 55 716
payment to supplier: creation 50.23 109 6027
payment to supplier: removal 3.71 46 445
service: total inventory turnovers 4.68 1111 562
service: total monetary turnovers 7.16 24 859
service: aggregate perf. data 0.90 23607 108
service: refresh index statistics 0.03 209910 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7566
TEST_TIME 2 8038
TEST_TIME 3 7346
TEST_TIME 4 6446
TEST_TIME 5 6167
TEST_TIME 6 5769
TEST_TIME 7 5908
TEST_TIME 8 6095
TEST_TIME 9 6433
TEST_TIME 10 6435
TEST_TIME 11 6333
TEST_TIME 12 6859
TEST_TIME 13 7489
TEST_TIME 14 7664
TEST_TIME 15 7683
TEST_TIME 16 7816
TEST_TIME 17 8049
TEST_TIME 18 8317
TEST_TIME 19 8575
TEST_TIME 20 8834
TEST_TIME 21 8838
TEST_TIME 22 9887
TEST_TIME 23 10135
TEST_TIME 24 10580
TEST_TIME 25 10298
TEST_TIME 26 10321
TEST_TIME 27 10781
TEST_TIME 28 10680
TEST_TIME 29 10545
TEST_TIME 30 10357
TEST_TIME 31 10317
TEST_TIME 32 10568
TEST_TIME 33 10417
TEST_TIME 34 10315
TEST_TIME 35 10336
TEST_TIME 36 10543
TEST_TIME 37 10364
TEST_TIME 38 10444
TEST_TIME 39 10500
TEST_TIME 40 10395
TEST_TIME 41 10458
TEST_TIME 42 10558
TEST_TIME 43 10431
TEST_TIME 44 10295
TEST_TIME 45 10191
TEST_TIME 46 10225
TEST_TIME 47 10096
TEST_TIME 48 10010
TEST_TIME 49 9973
TEST_TIME 50 9915
TEST_TIME 51 9908
TEST_TIME 52 9892
TEST_TIME 53 9888
TEST_TIME 54 9852
TEST_TIME 55 9901
TEST_TIME 56 9884
TEST_TIME 57 10051
TEST_TIME 58 10107
TEST_TIME 59 10151
TEST_TIME 60 10093
TEST_TIME 61 10135
TEST_TIME 62 10143
TEST_TIME 63 10212
TEST_TIME 64 10245
TEST_TIME 65 10298
TEST_TIME 66 10246
TEST_TIME 67 10281
TEST_TIME 68 10371
TEST_TIME 69 10407
TEST_TIME 70 10405
TEST_TIME 71 10291
TEST_TIME 72 10220
TEST_TIME 73 10308
TEST_TIME 74 10217
TEST_TIME 75 10135
TEST_TIME 76 10127
TEST_TIME 77 10068
TEST_TIME 78 10065
TEST_TIME 79 10021
TEST_TIME 80 9979
TEST_TIME 81 9976
TEST_TIME 82 10001
TEST_TIME 83 10005
TEST_TIME 84 10000
TEST_TIME 85 9962
TEST_TIME 86 9937
TEST_TIME 87 9948
TEST_TIME 88 9957
TEST_TIME 89 10072
TEST_TIME 90 10019
TEST_TIME 91 10045
TEST_TIME 92 10058
TEST_TIME 93 10025
TEST_TIME 94 10147
TEST_TIME 95 10187
TEST_TIME 96 10178
TEST_TIME 97 10223
TEST_TIME 98 10207
TEST_TIME 99 10183
TEST_TIME 100 10092
TEST_TIME 101 10078
TEST_TIME 102 10048
TEST_TIME 103 10021
TEST_TIME 104 10005
TEST_TIME 105 9985
TEST_TIME 106 9959
TEST_TIME 107 9956
TEST_TIME 108 9907
TEST_TIME 109 9884
TEST_TIME 110 9833
TEST_TIME 111 9797
TEST_TIME 112 9802
TEST_TIME 113 9798
TEST_TIME 114 9792
TEST_TIME 115 9786
TEST_TIME 116 9799
TEST_TIME 117 9871
TEST_TIME 118 9889
TEST_TIME 119 9849
TEST_TIME 120 9842

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1632388 1632388 0 0.00 0 31948 5 0 0
doc_list_biud 1632386 1632386 0 0.00 0 64821 25 0 0
sp_add_invoice_to_stock 5353 5258 95 1.77 30 170786 15981 0 95
sp_cancel_adding_invoice 6153 6153 0 0.00 4 46659 695 0 0
sp_cancel_client_order 1716 1716 0 0.00 4 13773 353 0 0
sp_cancel_customer_reserve 600050 600050 0 0.00 0 64823 52 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 4551 56 0 0
sp_cancel_pay_to_supplier 445 445 0 0.00 2 945 47 0 0
sp_cancel_supplier_invoice 6823 6814 9 0.13 1 18161 729 0 9
sp_cancel_supplier_order 434 434 0 0.00 8 933269 93349 0 0
sp_cancel_write_off 10657 10657 0 0.00 1 10480 104 0 0
sp_client_order 21380 21380 0 0.00 3 34041 326 0 0
sp_customer_reserve 489731 489731 0 0.00 0 56637 157 0 0
sp_fill_shopping_cart 32830 32830 0 0.00 1 22213 37 0 0
sp_fill_shopping_cart_clo_res 489719 489719 0 0.00 0 23316 13 0 0
sp_get_clo_for_invoice 5244 5244 0 0.00 0 43786 270 0 0
sp_kill_cost_storno 17971 17971 0 0.00 0 5270 45 0 0
sp_kill_qstorno_ret_qs2qd 1218005 1218005 0 0.00 0 64816 28 0 0
sp_kill_qty_storno 641296 641296 0 0.00 0 64821 62 0 0
sp_lock_selected_doc 21286 21286 0 0.00 0 6148 6 0 0
sp_make_cost_storno 28788 28788 0 0.00 0 11111 43 0 0
sp_multiply_rows_for_pdistr 28788 28788 0 0.00 0 10749 22 0 0
sp_multiply_rows_for_qdistr 21380 21380 0 0.00 0 25369 141 0 0
sp_pay_from_customer 7289 7289 0 0.00 4 32503 106 0 0
sp_pay_to_supplier 6027 6027 0 0.00 4 10355 110 0 0
sp_payment_common 13316 13316 0 0.00 1 32497 32 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 13765 330 0 0
sp_qd_handle_on_invoice_upd_sts 11396 11396 0 0.00 0 33410 313 0 0
sp_qd_handle_on_reserve_upd_sts 10229 10229 0 0.00 0 13132 60 0 0
sp_reserve_write_off 10229 10229 0 0.00 6 13169 159 0 0
sp_supplier_invoice 5733 5733 0 0.00 6 83733 3050 0 0
sp_supplier_order 5718 5718 0 0.00 5 27123 1317 0 0
srv_aggregate_perf_data 108 108 0 0.00 2837 815952 23608 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2148 127964 44808 0 0
srv_find_qd_qs_mism 1129608 1129608 0 0.00 0 31946 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 37 49521 1111 0 0
srv_make_money_saldo 859 859 0 0.00 1 3001 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 93198 267484 209910 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 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 5259 5259 0 0.00 0 11697 158 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 6737 80 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 8793 21 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 962 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 1428 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 445 445 0 0.00 0 2341 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 494 9 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 21577 1427 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 4043 10 0 0
t$perf-norm:sp_client_order 21380 21380 0 0.00 0 7273 16 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 3709 8 0 0
t$perf-norm:sp_pay_from_customer 7289 7289 0 0.00 0 6318 12 0 0
t$perf-norm:sp_pay_to_supplier 6027 6027 0 0.00 0 10982 14 0 0
t$perf-norm:sp_reserve_write_off 10229 10229 0 0.00 0 8758 10 0 0
t$perf-norm:sp_supplier_invoice 5733 5733 0 0.00 0 7506 16 0 0
t$perf-norm:sp_supplier_order 5718 5718 0 0.00 0 7775 14 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 3456 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1769 37 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 2086 19 0 0
t$perf-norm:srv_make_money_saldo 859 859 0 0.00 0 2130 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 3 0 0
v_add_invoice_to_stock 10676 10486 190 1.77 0 7227 14 0 190
v_all_customers 38562 38562 0 0.00 0 26 0 0 0
v_all_suppliers 31710 31710 0 0.00 0 65 1 0 0
v_all_wares 42760 42760 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 603 7 0 0
v_cancel_client_order 3432 3432 0 0.00 0 3395 5 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 473 3 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 1250 4 0 0
v_cancel_payment_to_supplier 890 890 0 0.00 0 588 3 0 0
v_cancel_supplier_invoice 794 776 18 2.26 0 603 13 0 18
v_cancel_supplier_order 868 868 0 0.00 0 251 3 0 0
v_cancel_write_off 2008 2008 0 0.00 0 5063 18 0 0
v_max_id_clo_ord 5718 5718 0 0.00 0 991 2 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 5998 19 0 0
v_max_id_ord_sup 5732 5732 0 0.00 0 11282 7 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 197 2 0 0
v_max_non_paid_realizn 4052 4052 0 0.00 0 5355 7 0 0
v_min_id_clo_ord 5718 5718 0 0.00 0 2008 5 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 12748 17 0 0
v_min_id_ord_sup 5732 5732 0 0.00 0 3939 5 0 0
v_min_non_paid_invoice 6027 6027 0 0.00 0 6751 19 0 0
v_min_non_paid_realizn 7289 7289 0 0.00 0 32493 26 0 0
v_our_firm 10670 10670 0 0.00 0 20 1 0 0
v_reserve_write_off 20456 20456 0 0.00 0 3491 4 0 0
x_lock_depdocs_on_canc_invoice 12579 12579 0 0.00 0 18650 111 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 4625 309 0 0
x_make_qstorno_1000_1200 5718 5718 0 0.00 2 27087 1252 0 0
x_make_qstorno_1200_2000 5733 5733 0 0.00 3 80694 2912 0 0
x_make_qstorno_2100_3300 489731 489731 0 0.00 0 46015 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-02-2023 01:39:50 to 20-02-2023 01:39:54.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:43 39 8192000000 8826730272 8848125952 22481648 0.273 8 4 5 0 22713776 92832 139296
23:09:44 30383 8192000000 9915018848 10228707328 997237440 10.852 100 88 176 0 1025747856 6112656 22397760
23:11:20 16635 8192000000 9965545776 10290577408 1046078592 11.323 100 93 186 0 1077953488 8315424 23559472
23:12:40 16375 8192000000 9988774176 10321354752 1068065312 11.534 100 89 176 1 1099672112 9345824 22260976
23:14:01 6837 8192000000 10022692912 10372751360 1109992496 11.932 100 67 133 1 1135207024 8940848 16273680
23:15:12 6964 8192000000 10087194320 10447085568 1172547616 12.521 100 80 161 0 1203309600 12841552 17920432
23:16:21 3365 8192000000 10130912368 10498392064 1219075984 12.953 100 87 173 0 1251076048 12938768 19061296
23:17:27 1946 8192000000 10164205136 10542977024 1254608752 13.281 100 87 172 0 1284956128 11755712 18591664
23:18:31 1513 8192000000 10184041936 10565775360 1273922080 13.457 100 77 155 0 1303491280 13027088 16542112
23:19:34 1072 8192000000 10222605296 10609721344 1307172704 13.760 100 90 178 1 1340499424 13407184 19919536
23:20:35 5462 8192000000 10204378224 10613587968 1309958016 13.786 100 45 90 0 1329362736 9850832 9553888
23:21:42 4953 8192000000 10211629552 10626244608 1322606976 13.900 100 34 69 0 1337746240 7973232 7166032
23:22:49 17480 8192000000 10208001520 10629373952 1324854640 13.921 100 12 24 0 1333649104 6365520 2428944
23:24:10 37993 8192000000 10224893216 10646044672 1329836048 13.966 100 24 48 0 1340990256 5931520 5222688
23:25:49 7701 8192000000 10237717552 10652585984 1336909472 14.030 100 29 56 0 1349779472 5862928 7007072
23:26:57 10258 8192000000 10238826144 10656190464 1340219792 14.059 100 14 29 0 1348420880 5238432 2962656
23:28:10 47788 8192000000 10182068688 10587054080 1267377856 13.398 96 39 79 0 1282106912 5455616 9273440
23:29:59 8268 8192000000 10170145792 10578685952 1259856208 13.329 98 24 48 0 1271368528 6021616 5490704
23:31:10 6102 8192000000 10115691104 10512613376 1186781616 12.653 96 27 54 0 1199066960 5360992 6924352
23:32:19 70843 8192000000 10109142944 10501996544 1154951760 12.356 92 35 70 0 1168613520 4654592 9007168
23:34:30 4959 8192000000 10160584592 10564120576 1266579584 13.390 99 32 63 0 1280511056 7147120 6784352
23:35:35 6617 8192000000 10195320400 10600488960 1293599968 13.637 100 36 70 0 1311845712 10244608 8001136
23:36:46 11815 8192000000 10198296704 10605981696 1299184736 13.688 100 24 48 1 1311034480 5530560 6319184
23:37:58 16739 8192000000 10209742752 10621243392 1314624800 13.828 100 29 57 0 1326336576 5803056 5908720
23:39:25 9028 8192000000 10253276224 10656649216 1336518064 14.026 100 60 118 1 1361445440 11363344 13564032
23:40:38 3845 8192000000 10265665920 10681417728 1355166096 14.194 100 56 111 0 1381706400 13458144 13082160
23:41:49 6066 8192000000 10278877328 10698006528 1370604352 14.332 100 43 85 0 1393613328 13587488 9421488
23:43:05 10480 8192000000 10301749760 10716053504 1380483520 14.421 100 55 108 0 1409616496 16391904 12741072
23:44:22 30976 8192000000 10305968816 10726932480 1389262208 14.499 100 39 78 0 1418246848 19756432 9228208
23:45:57 10836 8192000000 10323721392 10739810304 1388664096 14.494 99 58 115 0 1426035680 23975840 13395744
23:47:12 3928 8192000000 10338178400 10747486208 1398112976 14.578 100 71 137 0 1441464992 27511904 15840112
23:48:21 2147 8192000000 10321634544 10735288320 1386573152 14.475 100 62 124 0 1429812800 28300608 14939040
23:49:27 9319 8192000000 10317643920 10736410624 1371023024 14.336 99 57 112 0 1422876960 37421952 14431984
23:50:58 9039 8192000000 10325805312 10745528320 1376038352 14.381 99 57 109 0 1427633232 38082000 13512880
23:52:11 8135 8192000000 10326940544 10744045568 1384563552 14.457 100 68 136 0 1439689744 38493120 16633072
23:53:27 3789 8192000000 10337366544 10756415488 1392482832 14.528 100 57 110 4 1442201728 34952000 14766896
23:54:39 11198 8192000000 10334173136 10752180224 1377334736 14.393 99 61 121 2 1431398240 38367696 15695808
23:55:55 6439 8192000000 10332552976 10747039744 1370732272 14.334 99 65 127 2 1426032112 39118944 16180896
23:57:04 19872 8192000000 10309703520 10739412992 1349467696 14.143 97 37 75 0 1405308096 45721728 10118672
23:58:41 10854 8192000000 10295082960 10721173504 1350068112 14.148 99 33 65 1 1400357600 41672624 8616864
23:59:55 3363 8192000000 10327579728 10734743552 1358814192 14.227 100 80 157 1 1427640080 49221920 19603968
00:01:01 10320 8192000000 10286362688 10703904768 1336254480 14.024 99 43 84 0 1380794624 33477440 11062704
00:02:13 18093 8192000000 10253620672 10705571840 1326460352 13.935 98 21 42 2 1355180992 23480880 5239760
00:03:36 29688 8192000000 10274568400 10707726336 1351110160 14.157 99 28 56 0 1372709504 14558480 7040864
00:05:11 29218 8192000000 10253331216 10710044672 1344119520 14.095 99 10 20 0 1361860080 15279408 2461152
00:06:41 45817 8192000000 10243556976 10687057920 1311463088 13.799 97 4 8 1 1321458320 8997232 998000
00:08:28 97954 8192000000 10266178368 10710315008 1311451152 13.799 96 20 41 1 1332163824 16311456 4401216
00:11:07 50007 8192000000 10277548832 10702319616 1333596368 14.000 98 16 32 1 1344132336 7395312 3140656
00:12:58 65486 8192000000 10296141424 10737803264 1379158608 14.409 100 14 28 0 1400501008 18322512 3019888
00:15:05 90468 8192000000 10305207232 10742181888 1375475552 14.376 99 15 30 0 1390501072 11035152 3990368
00:17:36 42938 8192000000 10323645472 10752774144 1395511568 14.555 100 29 60 1 1410035952 7820528 6703856
00:19:22 63985 8192000000 10315898192 10763489280 1406054560 14.649 100 17 36 0 1419108928 8972784 4081584
00:21:31 33292 8192000000 10322241216 10759274496 1381811584 14.433 98 43 88 5 1397987872 6672800 9503488
00:23:07 77983 8192000000 10334817376 10777276416 1405569168 14.645 100 42 85 0 1426653248 10771328 10312752
00:25:27 16013 8192000000 10325266768 10783260672 1420330320 14.776 100 19 38 0 1436188528 11385792 4472416
00:26:47 23342 8192000000 10321545920 10772967424 1406433984 14.652 100 23 46 0 1428433184 16136544 5862656
00:28:15 35323 8192000000 10338026272 10775740416 1395678576 14.557 99 40 72 1 1422429792 17875616 8875600
00:29:53 65121 8192000000 10323169600 10777772032 1380513456 14.421 98 23 46 0 1413314688 26722224 6079008
00:33:04 62211 8192000000 10304281744 10755919872 1355337536 14.195 97 16 32 0 1383222032 23352704 4531792
00:35:11 65010 8192000000 10289795200 10772959232 1363127504 14.265 98 10 20 0 1385909376 20231872 2550000
00:37:18 55257 8192000000 10282042192 10739806208 1343600368 14.090 97 11 23 0 1355887328 9860544 2426416
00:39:16 17138 8192000000 10295048416 10735480832 1364543312 14.278 99 37 74 1 1384955920 12528672 7883936
00:40:38 127308 8192000000 10275330080 10734047232 1238043712 13.128 90 13 26 0 1250968880 10562544 2362624
00:43:45 85322 8192000000 10286053328 10725744640 1317141312 13.851 96 12 24 0 1338484576 18704992 2638272
00:46:15 59941 8192000000 10277667456 10729152512 1314597600 13.828 96 21 41 1 1332521488 13643424 4280464
00:48:27 104789 8192000000 10241630032 10683129856 1250882096 13.246 92 14 28 0 1264511728 9990640 3638992
00:51:33 83923 8192000000 10252775472 10706546688 1305548560 13.746 97 24 46 1 1323629568 12905936 5175072
00:54:02 32066 8192000000 10281987712 10708680704 1352737136 14.172 100 52 103 0 1374146736 9706208 11703392
00:55:38 49889 8192000000 10276683360 10729082880 1351038656 14.157 99 22 42 0 1369482352 13700432 4743264
00:57:33 47147 8192000000 10272157376 10724892672 1339831744 14.056 98 15 30 0 1358358784 14925520 3601520
00:59:26 76382 8192000000 10254856496 10710499328 1323038464 13.904 97 15 30 1 1341806640 14760688 4007488
01:01:43 79089 8192000000 10261262640 10722668544 1345156864 14.104 99 8 16 0 1360538256 13534016 1847376
01:04:03 32664 8192000000 10280257440 10723688448 1353674048 14.181 99 33 66 1 1370023056 7969744 8379264
01:05:37 56612 8192000000 10286291456 10750779392 1373442384 14.358 100 16 33 0 1389231456 11940336 3848736
01:07:48 127964 8192000000 10311007360 10760364032 1381132544 14.427 99 13 26 0 1393380240 9682144 2565552
01:10:56 77664 8192000000 10310104624 10782244864 1364392368 14.277 97 7 14 0 1375755952 10072336 1291248
01:13:14 81210 8192000000 10328046736 10801418240 1406246016 14.651 100 4 8 0 1428232592 21235616 750960
01:15:38 76920 8192000000 10310215328 10775785472 1373101008 14.355 98 19 38 0 1388872352 11423344 4348000
01:17:58 89060 8192000000 10301009456 10756718592 1359465104 14.233 97 14 28 0 1370101904 7409104 3227696
01:20:39 41221 8192000000 10302972128 10789429248 1401832352 14.611 100 11 19 0 1421342496 17219648 2290496
01:22:21 9240 8192000000 10286092592 10751528960 1380266240 14.419 98 26 50 1 1393092368 6466992 6359136
01:23:31 71913 8192000000 10280683264 10745679872 1319213472 13.870 94 16 32 0 1335345424 12303712 3828240
01:25:55 61165 8192000000 10260100560 10716680192 1328173984 13.951 96 13 24 0 1336413456 5993008 2246464
01:27:56 33366 8192000000 10244622224 10704478208 1316878208 13.848 97 8 16 0 1326806736 8070208 1858320
01:29:30 58788 8192000000 10267024128 10735509504 1354002272 14.183 99 9 18 0 1363071152 6984240 2084640
01:31:29 60477 8192000000 10292144384 10745020416 1369839024 14.326 99 18 36 0 1382479968 8381568 4259376
01:33:34 65559 8192000000 10283618928 10738442240 1354408784 14.187 98 22 44 0 1372716272 12987808 5319680
01:35:42 45726 8192000000 10278390080 10725539840 1332059152 13.986 97 17 34 0 1345037376 9201216 3777008
01:37:38 23095 8192000000 10289863952 10731147264 1358814768 14.227 100 42 84 0 1383506528 15683568 9008192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-02-2023 01:39:56 to 20-02-2023 01:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 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 20-02-2023 01:40:12 to 20-02-2023 01:40:14.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          243620
MON$OLDEST_ACTIVE               246137
MON$OLDEST_SNAPSHOT             246137
MON$NEXT_TRANSACTION            246137
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                       2059168
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

20.02.23 01:40:15. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-02-20 01:40:18 No severe PSQL-related problems occured
20.02.23 01:40:19. End of report.