Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2022 04:38
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 14662724 kB
MemAvailable 30011660 kB
Buffers 20 kB
Cached 16965452 kB
SwapCached 3328 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-12-20 04:37:22
DTS_END                         2022-12-20 04:37:22.3680
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(68:17)
IP                              ::1/34816
TRN_ID                          212144
ATT_ID                          588
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: 8647.26 26751 1037671
customer order: creation 159.43 506 19131
customer order: refuse 14.24 437 1709
order to supplier: creation 43.35 1477 5202
order to supplier: removal 3.21 200779 385
invoice (draft): creation 42.38 3420 5086
invoice (draft): removal 47.31 1192 5677
invoice accept: apply 39.31 26462 4717
invoice accept: cancel 43.22 1381 5186
customer reserve: creation 3730.85 242 447702
customer reserve: removal 4245.74 118 509489
realization accept: apply 78.99 312 9479
realization accept: cancel 74.13 208 8895
payment from customer: creation 57.65 210 6918
payment from customer: removal 5.58 180 669
payment to supplier: creation 46.55 231 5586
payment to supplier: removal 3.34 145 401
service: total inventory turnovers 3.69 4291 443
service: total monetary turnovers 7.38 407 885
service: aggregate perf. data 0.89 15825 107
service: refresh index statistics 0.03 277199 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-12-2022 04:38:43 to 20-12-2022 04:38:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5917
TEST_TIME 2 4992
TEST_TIME 3 4798
TEST_TIME 4 4502
TEST_TIME 5 4474
TEST_TIME 6 4941
TEST_TIME 7 5432
TEST_TIME 8 5556
TEST_TIME 9 5778
TEST_TIME 10 6070
TEST_TIME 11 6089
TEST_TIME 12 6096
TEST_TIME 13 6237
TEST_TIME 14 6387
TEST_TIME 15 6555
TEST_TIME 16 6732
TEST_TIME 17 6911
TEST_TIME 18 7042
TEST_TIME 19 7028
TEST_TIME 20 7025
TEST_TIME 21 7055
TEST_TIME 22 7057
TEST_TIME 23 7313
TEST_TIME 24 7567
TEST_TIME 25 7768
TEST_TIME 26 7943
TEST_TIME 27 8141
TEST_TIME 28 8545
TEST_TIME 29 8531
TEST_TIME 30 8796
TEST_TIME 31 8879
TEST_TIME 32 9136
TEST_TIME 33 9522
TEST_TIME 34 9766
TEST_TIME 35 10243
TEST_TIME 36 10392
TEST_TIME 37 10183
TEST_TIME 38 10124
TEST_TIME 39 10250
TEST_TIME 40 10084
TEST_TIME 41 10003
TEST_TIME 42 9874
TEST_TIME 43 9724
TEST_TIME 44 9787
TEST_TIME 45 9772
TEST_TIME 46 9880
TEST_TIME 47 9805
TEST_TIME 48 9706
TEST_TIME 49 9725
TEST_TIME 50 9575
TEST_TIME 51 9573
TEST_TIME 52 9637
TEST_TIME 53 9596
TEST_TIME 54 9468
TEST_TIME 55 9300
TEST_TIME 56 9195
TEST_TIME 57 9187
TEST_TIME 58 9154
TEST_TIME 59 9155
TEST_TIME 60 9030
TEST_TIME 61 9001
TEST_TIME 62 8910
TEST_TIME 63 8933
TEST_TIME 64 8906
TEST_TIME 65 8864
TEST_TIME 66 8891
TEST_TIME 67 8854
TEST_TIME 68 9027
TEST_TIME 69 9079
TEST_TIME 70 9085
TEST_TIME 71 9069
TEST_TIME 72 9038
TEST_TIME 73 9045
TEST_TIME 74 9088
TEST_TIME 75 9055
TEST_TIME 76 9092
TEST_TIME 77 9155
TEST_TIME 78 9141
TEST_TIME 79 9212
TEST_TIME 80 9191
TEST_TIME 81 9135
TEST_TIME 82 9072
TEST_TIME 83 9010
TEST_TIME 84 8950
TEST_TIME 85 8963
TEST_TIME 86 8927
TEST_TIME 87 8888
TEST_TIME 88 8843
TEST_TIME 89 8772
TEST_TIME 90 8728
TEST_TIME 91 8849
TEST_TIME 92 8859
TEST_TIME 93 8845
TEST_TIME 94 8820
TEST_TIME 95 8819
TEST_TIME 96 8856
TEST_TIME 97 8836
TEST_TIME 98 8853
TEST_TIME 99 8981
TEST_TIME 100 8987
TEST_TIME 101 9000
TEST_TIME 102 9003
TEST_TIME 103 8974
TEST_TIME 104 9004
TEST_TIME 105 8926
TEST_TIME 106 8960
TEST_TIME 107 8917
TEST_TIME 108 8908
TEST_TIME 109 8867
TEST_TIME 110 8834
TEST_TIME 111 8821
TEST_TIME 112 8769
TEST_TIME 113 8795
TEST_TIME 114 8766
TEST_TIME 115 8734
TEST_TIME 116 8783
TEST_TIME 117 8762
TEST_TIME 118 8757
TEST_TIME 119 8734
TEST_TIME 120 8675

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-12-2022 04:38:47 to 20-12-2022 04:38:50.

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 1449574 1449574 0 0.00 0 44019 14 0 0
doc_list_biud 1449564 1449564 0 0.00 0 39335 48 0 0
sp_add_invoice_to_stock 4835 4717 118 2.44 23 176825 26463 0 118
sp_cancel_adding_invoice 5186 5186 0 0.00 5 80641 1381 0 0
sp_cancel_client_order 1709 1709 0 0.00 6 10308 437 0 0
sp_cancel_customer_reserve 509489 509489 0 0.00 1 27326 118 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 4 19849 180 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 4316 146 0 0
sp_cancel_supplier_invoice 5685 5677 8 0.14 2 39341 1193 0 8
sp_cancel_supplier_order 385 385 0 0.00 11 1575388 200780 0 0
sp_cancel_write_off 8895 8895 0 0.00 2 19852 209 0 0
sp_client_order 19131 19131 0 0.00 6 21121 507 0 0
sp_customer_reserve 447702 447702 0 0.00 1 120957 242 0 0
sp_fill_shopping_cart 29406 29406 0 0.00 1 13323 89 0 0
sp_fill_shopping_cart_clo_res 447669 447669 0 0.00 0 12738 19 0 0
sp_get_clo_for_invoice 4674 4674 0 0.00 0 30405 320 0 0
sp_kill_cost_storno 15151 15151 0 0.00 0 15029 91 0 0
sp_kill_qstorno_ret_qs2qd 1033935 1033935 0 0.00 0 39334 51 0 0
sp_kill_qty_storno 545493 545493 0 0.00 0 39335 118 0 0
sp_lock_selected_doc 19747 19747 0 0.00 0 10073 9 0 0
sp_make_cost_storno 26657 26657 0 0.00 0 44016 89 0 0
sp_multiply_rows_for_pdistr 26656 26656 0 0.00 0 14391 46 0 0
sp_multiply_rows_for_qdistr 19131 19131 0 0.00 0 15136 186 0 0
sp_pay_from_customer 6918 6918 0 0.00 5 20004 210 0 0
sp_pay_to_supplier 5586 5586 0 0.00 6 44025 232 0 0
sp_payment_common 12503 12503 0 0.00 2 6632 50 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 1 10296 364 0 0
sp_qd_handle_on_invoice_upd_sts 9859 9859 0 0.00 0 29988 618 0 0
sp_qd_handle_on_reserve_upd_sts 9479 9479 0 0.00 0 16626 71 0 0
sp_reserve_write_off 9479 9479 0 0.00 9 20056 313 0 0
sp_supplier_invoice 5086 5086 0 0.00 10 98048 3421 0 0
sp_supplier_order 5202 5202 0 0.00 7 55487 1477 0 0
srv_aggregate_perf_data 107 107 0 0.00 2019 187064 15825 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 754 156700 34099 0 0
srv_find_qd_qs_mism 990759 990759 0 0.00 0 26596 10 0 0
srv_make_invnt_saldo 443 443 0 0.00 68 56230 4292 0 0
srv_make_money_saldo 885 885 0 0.00 1 24503 407 0 0
srv_recalc_idx_stat 4 4 0 0.00 120489 387990 277199 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4720 4720 0 0.00 1 10610 1024 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 6912 450 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 3619 31 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 2453 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 1277 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 822 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 623 31 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 120660 9142 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 6703 25 0 0
t$perf-norm:sp_client_order 19132 19132 0 0.00 0 17295 46 0 0
t$perf-norm:sp_customer_reserve 925 925 0 0.00 0 16364 39 0 0
t$perf-norm:sp_pay_from_customer 6918 6918 0 0.00 0 14622 27 0 0
t$perf-norm:sp_pay_to_supplier 5586 5586 0 0.00 0 17378 26 0 0
t$perf-norm:sp_reserve_write_off 9480 9480 0 0.00 0 7264 26 0 0
t$perf-norm:sp_supplier_invoice 5086 5086 0 0.00 0 10973 38 0 0
t$perf-norm:sp_supplier_order 5205 5205 0 0.00 0 17273 42 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 324 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 134 9 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 2356 23 0 0
t$perf-norm:srv_make_money_saldo 885 885 0 0.00 0 3203 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 1434 367 0 0
v_add_invoice_to_stock 9576 9340 236 2.46 0 19624 36 0 236
v_all_customers 35530 35530 0 0.00 0 115 4 0 0
v_all_suppliers 28830 28830 0 0.00 0 171 5 0 0
v_all_wares 38252 38252 0 0.00 0 232 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 3523 18 0 0
v_cancel_client_order 3418 3418 0 0.00 0 2192 11 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 19767 36 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 3685 15 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 4164 19 0 0
v_cancel_supplier_invoice 754 738 16 2.12 0 2186 25 0 16
v_cancel_supplier_order 770 770 0 0.00 0 1102 12 0 0
v_cancel_write_off 1958 1958 0 0.00 0 19716 46 0 0
v_max_id_clo_ord 5200 5200 0 0.00 0 1128 5 0 0
v_max_id_clo_res 925 925 0 0.00 0 2394 18 0 0
v_max_id_ord_sup 5079 5079 0 0.00 0 2659 6 0 0
v_max_non_paid_invoice 1450 1450 0 0.00 0 156 8 0 0
v_max_non_paid_realizn 3553 3553 0 0.00 0 3612 9 0 0
v_min_id_clo_ord 5200 5200 0 0.00 0 1648 9 0 0
v_min_id_clo_res 925 925 0 0.00 0 6017 19 0 0
v_min_id_ord_sup 5079 5079 0 0.00 0 3130 8 0 0
v_min_non_paid_invoice 5586 5586 0 0.00 0 6627 20 0 0
v_min_non_paid_realizn 6917 6917 0 0.00 0 6224 18 0 0
v_our_firm 9450 9450 0 0.00 0 117 5 0 0
v_reserve_write_off 18958 18958 0 0.00 0 19794 16 0 0
x_lock_depdocs_on_canc_invoice 10494 10494 0 0.00 0 27926 140 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 7475 413 0 0
x_make_qstorno_1000_1200 5202 5202 0 0.00 2 55456 1304 0 0
x_make_qstorno_1200_2000 5086 5086 0 0.00 4 97924 3163 0 0
x_make_qstorno_2100_3300 447702 447702 0 0.00 0 120945 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2022 04:38:51 to 20-12-2022 04:38:55.

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
02:07:26 3 8192000000 8801410432 8813793280 6218864 0.075 3 1 2 0 6320096 65456 35776
02:08:26 25535 8192000000 9714167328 10008444928 789672512 8.792 100 90 180 0 819168240 7803472 21692256
02:09:56 26690 8192000000 9760050720 10064551936 829266880 9.192 100 93 186 0 863400224 11644672 22488672
02:11:25 13976 8192000000 9787630208 10104471552 859613408 9.496 100 87 174 0 900529440 20685456 20230576
02:13:09 5554 8192000000 9864602880 10199207936 916543440 10.062 100 82 160 0 973198576 38995184 17659952
02:14:18 4833 8192000000 9928065840 10271105024 965737280 10.545 100 89 175 0 1039335984 55742512 17856192
02:15:28 2572 8192000000 9983835344 10339049472 996891312 10.848 100 89 178 1 1098183936 83824160 17468464
02:16:32 3650 8192000000 9983962000 10346455040 1024489520 11.115 100 81 162 0 1106105392 66158768 15457104
02:17:40 1136 8192000000 10008016816 10376794112 1045182000 11.314 100 88 166 0 1125420016 63378304 16859712
02:18:43 1114 8192000000 10044718768 10418593792 1060372544 11.460 100 95 188 0 1165818336 86137024 19308768
02:19:46 971 8192000000 10047912368 10428223488 1066795600 11.521 100 91 179 1 1172083984 87294080 17994304
02:20:49 1320 8192000000 10053995184 10435973120 1073529776 11.586 100 93 181 2 1178589072 86115760 18943536
02:21:52 959 8192000000 10054784176 10444484608 1081054032 11.658 100 95 184 0 1177583360 78673904 17855424
02:22:54 1308 8192000000 10072960336 10457067520 1085326736 11.698 100 96 186 4 1198085312 94388816 18369760
02:23:56 872 8192000000 10066527072 10454769664 1094776384 11.788 100 99 185 1 1190502544 76650256 19075904
02:24:58 1184 8192000000 10067795584 10455494656 1095687744 11.797 100 95 181 2 1190665280 77209696 17767840
02:26:01 970 8192000000 10057385680 10446462976 1096460176 11.804 100 93 171 1 1181051792 64833296 19758320
02:27:03 946 8192000000 10059571120 10449993728 1099674848 11.835 100 90 171 0 1185733888 68382704 17676336
02:28:05 971 8192000000 10067137696 10455478272 1097495984 11.814 100 83 160 0 1188342544 75129376 15717184
02:29:06 4174 8192000000 10044464256 10435993600 1094481776 11.785 100 72 136 1 1166475504 57628192 14365536
02:30:11 1327 8192000000 10027073216 10405376000 1067388160 11.527 100 92 171 0 1149738432 64650608 17699664
02:31:14 1436 8192000000 10034818640 10419957760 1071319152 11.565 100 89 163 0 1152735456 63727312 17688992
02:32:17 926 8192000000 10016611392 10396991488 1053409840 11.393 100 87 167 0 1137198880 66968432 16820608
02:33:20 3585 8192000000 9999113696 10383978496 1041076960 11.275 100 73 140 1 1125643296 70961808 13604528
02:34:24 947 8192000000 9984484000 10360602624 1035710000 11.223 100 86 170 2 1104888208 51654160 17524048
02:35:27 1002 8192000000 9964736080 10335526912 1014248400 11.016 100 84 166 2 1089077376 57354496 17474480
02:36:28 2551 8192000000 10001364320 10380754944 1038249728 11.248 100 60 113 0 1117210240 67282944 11677568
02:37:38 14196 8192000000 10013211232 10396581888 1058736960 11.444 100 67 129 0 1137465248 66270720 12457568
02:38:59 10587 8192000000 10021902896 10404278272 1062969280 11.485 100 80 149 3 1139879792 61271712 15638800
02:40:19 9670 8192000000 10065481264 10448633856 1083721200 11.683 100 64 118 2 1175614912 80505072 11388640
02:41:39 9088 8192000000 10088984368 10489569280 1098418048 11.823 100 60 120 0 1210551168 98067824 14065296
02:42:50 9775 8192000000 10150583408 10543325184 1108768416 11.921 100 73 138 0 1261846112 137451712 15625984
02:44:05 1356 8192000000 10153568496 10556309504 1124029872 12.065 100 90 167 0 1271770768 129176784 18564112
02:45:09 874 8192000000 10193492576 10587291648 1134531328 12.164 100 96 186 0 1311542944 155850400 21161216
02:46:20 1587 8192000000 10216911280 10618294272 1146712080 12.279 100 81 154 0 1342712336 179541600 16458656
02:47:23 1385 8192000000 10265920592 10663530496 1153564448 12.343 100 89 171 1 1378183216 204115744 20503024
02:48:29 4715 8192000000 10278469024 10675179520 1161211808 12.415 100 77 148 2 1378924624 202097776 15615040
02:49:37 4330 8192000000 10335007488 10740887552 1155930432 12.365 100 78 156 0 1447838144 273885584 18022128
02:50:46 761 8192000000 10365729792 10778681344 1173451264 12.529 100 97 186 0 1477664560 282191360 22021936
02:51:50 1647 8192000000 10415667792 10828218368 1181066192 12.600 100 95 179 0 1523990208 320808816 22115200
02:52:55 1725 8192000000 10398267536 10837008384 1189835648 12.682 100 90 176 1 1505457040 295340816 20280576
02:54:01 1064 8192000000 10453923232 10883747840 1202518976 12.800 100 98 192 2 1562739552 338229264 21991312
02:55:06 1942 8192000000 10455898736 10881695744 1205115328 12.824 100 96 188 0 1565002288 336424816 23462144
02:56:13 801 8192000000 10504457744 10919313408 1214482960 12.911 100 99 193 0 1607646656 370397296 22766400
02:57:17 1493 8192000000 10553909168 10968911872 1211051744 12.879 100 98 196 0 1661459904 427553760 22854400
02:58:22 972 8192000000 10568064960 10981986304 1189556256 12.679 99 97 191 1 1672215680 459396432 23262992
02:59:28 993 8192000000 10563509648 11030560768 1206162304 12.834 100 95 187 2 1668519056 440055056 22301696
03:00:35 977 8192000000 10577609408 11020546048 1212939904 12.896 100 81 154 2 1684043024 451637312 19465808
03:01:38 744 8192000000 10565062448 11033518080 1207397856 12.845 100 98 192 0 1671599104 441175360 23025888
03:02:44 804 8192000000 10579420800 11037892608 1211138688 12.880 100 88 167 0 1680764176 448945776 20679712
03:03:48 4689 8192000000 10547192816 11024265216 1204235408 12.816 100 66 126 1 1652884624 432192496 16456720
03:04:55 6379 8192000000 10543985568 11049771008 1201907184 12.794 100 70 135 3 1670558032 450905488 17745360
03:06:04 24943 8192000000 10525734720 11015741440 1186321728 12.649 100 42 84 1 1627557568 430030192 11205648
03:07:36 12948 8192000000 10470782304 10951979008 1183000032 12.618 100 37 74 0 1591460528 398724208 9736288
03:08:55 9882 8192000000 10438462848 10910650368 1167054320 12.469 99 37 72 0 1562812048 386437056 9320672
03:10:49 17950 8192000000 10310041728 10784555008 1150633792 12.315 100 21 42 0 1412862000 256927488 5300720
03:12:11 19841 8192000000 10161342656 10634272768 1134723776 12.166 98 39 78 0 1263623632 119830048 9069808
03:13:33 11095 8192000000 10117159872 10554527744 1130029936 12.122 98 54 97 3 1205778608 64285744 11462928
03:14:47 27375 8192000000 10137936016 10573774848 1139094288 12.207 100 38 66 1 1240007680 92890080 8023312
03:16:16 49843 8192000000 10104878848 10548334592 1107575392 11.909 97 25 46 1 1182162224 69528656 5058176
03:18:09 96368 8192000000 10123898592 10598678528 1092144992 11.763 96 23 46 1 1187308576 90354496 4809088
03:21:00 53962 8192000000 10137113456 10606845952 1139135312 12.207 100 28 57 0 1224833888 79546112 6152464
03:22:55 88119 8192000000 10053166848 10532028416 1104184672 11.877 97 9 17 0 1179561920 73624464 1752784
03:25:30 79531 8192000000 10070240720 10539331584 1123882304 12.064 99 18 36 1 1162170128 34472800 3815024
03:27:56 6716 8192000000 10131191584 10549104640 1155950016 12.365 100 75 140 4 1216472864 45298608 15224240
03:29:08 156699 8192000000 10067302752 10526502912 1147123968 12.282 100 10 18 0 1226190480 77113248 1953264
03:33:03 122140 8192000000 10111600384 10573524992 1125607776 12.080 98 42 77 0 1211002416 76195408 9199232
03:36:07 67483 8192000000 10118688752 10563371008 1147495088 12.286 100 22 44 0 1224328048 72326240 4506720
03:38:15 41220 8192000000 10167466016 10603175936 1116546336 11.994 96 39 74 1 1233425184 108462272 8416576
03:39:58 11350 8192000000 10245575248 10662658048 1144499536 12.258 100 85 162 2 1340061616 177684560 17877520
03:41:11 90641 8192000000 10225397584 10705727488 1141467040 12.229 100 20 40 0 1342921072 196505472 4948560
03:43:43 25375 8192000000 10226610144 10661650432 1148562128 12.296 100 42 84 0 1309830960 152529184 8739648
03:45:59 78577 8192000000 10067068240 10557026304 1118951456 12.017 98 10 19 0 1171280544 50401520 1927568
03:48:19 16257 8192000000 10078282464 10508025856 1123915696 12.064 99 44 87 1 1153657328 21256672 8484960
03:49:58 17931 8192000000 10115165952 10558619648 1140288496 12.218 100 61 116 0 1201572592 48989968 12294128
03:51:22 35526 8192000000 10091887120 10565738496 1133544336 12.155 99 28 56 0 1187699136 48687808 5466992
03:52:58 94737 8192000000 10073564816 10570702848 1125469216 12.079 98 15 28 1 1202169008 72687664 4012128
03:55:38 59076 8192000000 10119119664 10592505856 1141798096 12.232 100 31 62 0 1214268960 66359968 6110896
03:57:37 76145 8192000000 10092073488 10555305984 1105855024 11.893 97 30 60 0 1149163088 37483056 5825008
04:00:36 59077 8192000000 10058418544 10500890624 1115228912 11.982 99 39 73 0 1158504976 35470864 7805200
04:02:37 73049 8192000000 10052061728 10497761280 1093185600 11.773 97 16 30 0 1136106848 39743744 3177504
04:04:56 85192 8192000000 10144730928 10607423488 1148726864 12.298 100 20 40 0 1238314272 84885424 4701984
04:07:33 65874 8192000000 10131155120 10591145984 1119225632 12.020 98 22 42 1 1231383872 106964816 5193424
04:09:39 36094 8192000000 10095655040 10529239040 1117549824 12.004 98 34 68 0 1165998976 40987504 7461648
04:11:18 35932 8192000000 10108551712 10542956544 1085596560 11.701 96 32 62 1 1156243824 64271424 6375840
04:12:55 38436 8192000000 10104725872 10544545792 1113213328 11.963 99 42 84 0 1229197136 106907584 9076224
04:14:37 20112 8192000000 10166282400 10597666816 1124206256 12.067 99 52 96 2 1240852608 105964480 10681872
04:15:59 52777 8192000000 10084538720 10578288640 1121519120 12.041 99 23 46 0 1264001840 137444736 5037984
04:17:56 27661 8192000000 10097076304 10549972992 1118437680 12.012 99 28 56 0 1169449552 45860704 5151168
04:19:32 39985 8192000000 10104604224 10546532352 1100780512 11.845 98 40 80 0 1159117696 50696832 7640352
04:21:13 29971 8192000000 10143227104 10617274368 1133873200 12.158 100 37 74 0 1273929552 132463760 7592592
04:22:49 87796 8192000000 10064682176 10546360320 1116978304 11.998 99 25 44 1 1170651760 48066304 5607152
04:25:18 47988 8192000000 10090975616 10564177920 1128524016 12.107 99 22 44 0 1178442688 45289520 4629152
04:27:11 82376 8192000000 10082547168 10551771136 1108018944 11.914 97 14 28 0 1163403792 52680288 2704560
04:29:35 69455 8192000000 10059313840 10562547712 1109504432 11.928 98 12 23 0 1176272304 64403392 2364480
04:32:13 58484 8192000000 10099508064 10555858944 1118041280 12.008 99 14 28 0 1173356112 52216768 3098064
04:34:13 14461 8192000000 10062055056 10501341184 1125243600 12.077 100 44 85 1 1177343568 44233280 7866688
04:35:34 26403 8192000000 10105840032 10543538176 1133963632 12.159 100 23 44 0 1192753264 54062416 4727216
04:37:03 19467 8192000000 10147206224 10576449536 1119564976 12.023 96 41 82 0 1229674112 101333840 8775296

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-12-2022 04:38:57 to 20-12-2022 04:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2022 04:39:14 to 20-12-2022 04:39:16.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          208917
MON$OLDEST_ACTIVE               212801
MON$OLDEST_SNAPSHOT             212801
MON$NEXT_TRANSACTION            212801
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-30 07:30:08.6030 GMT
MON$PAGES                       2065184
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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-oltp40 according to config parameter 'remove_isql_logs'

20.12.22 04:39:18. There are 3 logs in /var/tmp/logs-oltp40 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-12-20 04:39:20 No severe PSQL-related problems occured
20.12.22 04:39:21. End of report.