Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.10.2022 13: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 15476236 kB
MemAvailable 30556852 kB
Buffers 0 kB
Cached 16237600 kB
SwapCached 2112 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-10-04 13:37:22
DTS_END                         2022-10-04 13:37:22.7280
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/40932
TRN_ID                          222789
ATT_ID                          655
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: 9012.71 19949 1081525
customer order: creation 177.26 470 21271
customer order: refuse 15.28 385 1834
order to supplier: creation 46.55 1404 5586
order to supplier: removal 3.52 153740 422
invoice (draft): creation 46.08 2814 5530
invoice (draft): removal 49.23 984 5908
invoice accept: apply 42.85 26033 5142
invoice accept: cancel 44.68 1186 5361
customer reserve: creation 3946.55 253 473586
customer reserve: removal 4345.59 98 521471
realization accept: apply 84.96 263 10195
realization accept: cancel 79.63 153 9555
payment from customer: creation 59.70 165 7164
payment from customer: removal 6.10 105 732
payment to supplier: creation 48.37 205 5804
payment to supplier: removal 3.43 95 411
service: total inventory turnovers 4.32 3589 518
service: total monetary turnovers 7.60 360 912
service: aggregate perf. data 0.98 14899 118
service: refresh index statistics 0.04 191770 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8836
TEST_TIME 2 6787
TEST_TIME 3 6365
TEST_TIME 4 6148
TEST_TIME 5 6147
TEST_TIME 6 6154
TEST_TIME 7 6406
TEST_TIME 8 6376
TEST_TIME 9 6395
TEST_TIME 10 6616
TEST_TIME 11 6845
TEST_TIME 12 6581
TEST_TIME 13 6675
TEST_TIME 14 6657
TEST_TIME 15 6690
TEST_TIME 16 6795
TEST_TIME 17 6870
TEST_TIME 18 6903
TEST_TIME 19 7310
TEST_TIME 20 7545
TEST_TIME 21 7668
TEST_TIME 22 8040
TEST_TIME 23 8432
TEST_TIME 24 8503
TEST_TIME 25 8627
TEST_TIME 26 8831
TEST_TIME 27 8820
TEST_TIME 28 9947
TEST_TIME 29 10233
TEST_TIME 30 10773
TEST_TIME 31 10742
TEST_TIME 32 10789
TEST_TIME 33 10693
TEST_TIME 34 10776
TEST_TIME 35 10657
TEST_TIME 36 10646
TEST_TIME 37 10604
TEST_TIME 38 10430
TEST_TIME 39 10262
TEST_TIME 40 10097
TEST_TIME 41 10131
TEST_TIME 42 10094
TEST_TIME 43 9962
TEST_TIME 44 9960
TEST_TIME 45 9871
TEST_TIME 46 9816
TEST_TIME 47 9798
TEST_TIME 48 9720
TEST_TIME 49 9683
TEST_TIME 50 9632
TEST_TIME 51 9596
TEST_TIME 52 9681
TEST_TIME 53 9868
TEST_TIME 54 9862
TEST_TIME 55 9894
TEST_TIME 56 9971
TEST_TIME 57 9902
TEST_TIME 58 9848
TEST_TIME 59 9810
TEST_TIME 60 9758
TEST_TIME 61 9923
TEST_TIME 62 9979
TEST_TIME 63 9939
TEST_TIME 64 9810
TEST_TIME 65 9788
TEST_TIME 66 9691
TEST_TIME 67 9643
TEST_TIME 68 9669
TEST_TIME 69 9596
TEST_TIME 70 9555
TEST_TIME 71 9501
TEST_TIME 72 9449
TEST_TIME 73 9520
TEST_TIME 74 9506
TEST_TIME 75 9452
TEST_TIME 76 9414
TEST_TIME 77 9453
TEST_TIME 78 9483
TEST_TIME 79 9431
TEST_TIME 80 9487
TEST_TIME 81 9475
TEST_TIME 82 9511
TEST_TIME 83 9482
TEST_TIME 84 9633
TEST_TIME 85 9528
TEST_TIME 86 9465
TEST_TIME 87 9442
TEST_TIME 88 9384
TEST_TIME 89 9317
TEST_TIME 90 9285
TEST_TIME 91 9283
TEST_TIME 92 9260
TEST_TIME 93 9247
TEST_TIME 94 9239
TEST_TIME 95 9232
TEST_TIME 96 9272
TEST_TIME 97 9244
TEST_TIME 98 9255
TEST_TIME 99 9276
TEST_TIME 100 9271
TEST_TIME 101 9272
TEST_TIME 102 9301
TEST_TIME 103 9294
TEST_TIME 104 9365
TEST_TIME 105 9310
TEST_TIME 106 9247
TEST_TIME 107 9250
TEST_TIME 108 9226
TEST_TIME 109 9209
TEST_TIME 110 9192
TEST_TIME 111 9148
TEST_TIME 112 9121
TEST_TIME 113 9098
TEST_TIME 114 9078
TEST_TIME 115 9076
TEST_TIME 116 9063
TEST_TIME 117 9088
TEST_TIME 118 9089
TEST_TIME 119 9085
TEST_TIME 120 9043

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-10-2022 13:38:47 to 04-10-2022 13:38:51.

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 1518236 1518236 0 0.00 0 21296 13 0 0
doc_list_biud 1518220 1518220 0 0.00 0 47508 39 0 0
sp_add_invoice_to_stock 5294 5142 152 2.87 63 230909 26034 0 152
sp_cancel_adding_invoice 5361 5361 0 0.00 6 123375 1186 0 0
sp_cancel_client_order 1834 1834 0 0.00 6 8769 385 0 0
sp_cancel_customer_reserve 521471 521471 0 0.00 1 47528 99 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 3 1523 105 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 4 1805 96 0 0
sp_cancel_supplier_invoice 5916 5908 8 0.13 2 21208 985 0 8
sp_cancel_supplier_order 422 422 0 0.00 8 1398281 153741 0 0
sp_cancel_write_off 9555 9555 0 0.00 1 10563 154 0 0
sp_client_order 21271 21271 0 0.00 6 14541 470 0 0
sp_customer_reserve 473586 473586 0 0.00 0 130770 253 0 0
sp_fill_shopping_cart 32377 32377 0 0.00 1 12686 74 0 0
sp_fill_shopping_cart_clo_res 473543 473543 0 0.00 0 13159 20 0 0
sp_get_clo_for_invoice 5094 5094 0 0.00 0 23233 284 0 0
sp_kill_cost_storno 16059 16059 0 0.00 0 21287 65 0 0
sp_kill_qstorno_ret_qs2qd 1058827 1058827 0 0.00 0 47466 44 0 0
sp_kill_qty_storno 559839 559839 0 0.00 0 47506 99 0 0
sp_lock_selected_doc 21253 21253 0 0.00 0 7020 7 0 0
sp_make_cost_storno 28256 28256 0 0.00 0 16345 80 0 0
sp_multiply_rows_for_pdistr 28256 28256 0 0.00 0 9314 36 0 0
sp_multiply_rows_for_qdistr 21271 21271 0 0.00 0 14263 207 0 0
sp_pay_from_customer 7164 7164 0 0.00 5 12015 165 0 0
sp_pay_to_supplier 5804 5804 0 0.00 7 16617 206 0 0
sp_payment_common 12968 12968 0 0.00 2 14464 47 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 1 8104 310 0 0
sp_qd_handle_on_invoice_upd_sts 10454 10454 0 0.00 0 27914 457 0 0
sp_qd_handle_on_reserve_upd_sts 10195 10195 0 0.00 0 13486 55 0 0
sp_reserve_write_off 10195 10195 0 0.00 8 23302 264 0 0
sp_supplier_invoice 5530 5530 0 0.00 8 79553 2815 0 0
sp_supplier_order 5586 5586 0 0.00 7 26069 1405 0 0
srv_aggregate_perf_data 118 118 0 0.00 2714 123744 14899 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 876 131060 34923 0 0
srv_find_qd_qs_mism 1032000 1032000 0 0.00 0 12665 11 0 0
srv_make_invnt_saldo 518 518 0 0.00 56 72051 3590 0 0
srv_make_money_saldo 912 912 0 0.00 1 17037 360 0 0
srv_recalc_idx_stat 5 5 0 0.00 47250 271576 191771 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 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 5147 5147 0 0.00 1 13210 809 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 1 16295 410 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 1586 26 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 3288 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 2422 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 741 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 2359 28 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 75310 5417 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 1435 15 0 0
t$perf-norm:sp_client_order 21271 21271 0 0.00 0 13457 33 0 0
t$perf-norm:sp_customer_reserve 978 978 0 0.00 0 2192 19 0 0
t$perf-norm:sp_pay_from_customer 7164 7164 0 0.00 0 6582 17 0 0
t$perf-norm:sp_pay_to_supplier 5804 5804 0 0.00 0 7339 18 0 0
t$perf-norm:sp_reserve_write_off 10195 10195 0 0.00 0 7570 21 0 0
t$perf-norm:sp_supplier_invoice 5530 5530 0 0.00 0 5236 27 0 0
t$perf-norm:sp_supplier_order 5586 5586 0 0.00 0 13087 31 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 265 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 523 20 0 0
t$perf-norm:srv_make_invnt_saldo 518 518 0 0.00 0 3751 17 0 0
t$perf-norm:srv_make_money_saldo 912 912 0 0.00 0 544 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 187 45 0 0
v_add_invoice_to_stock 10490 10186 304 2.89 0 2237 22 0 304
v_all_customers 38320 38320 0 0.00 0 138 4 0 0
v_all_suppliers 30636 30636 0 0.00 0 157 4 0 0
v_all_wares 42540 42540 0 0.00 0 67 4 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 658 15 0 0
v_cancel_client_order 3668 3668 0 0.00 0 3085 12 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 915 11 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 1882 13 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 596 10 0 0
v_cancel_supplier_invoice 896 880 16 1.78 0 1309 23 0 16
v_cancel_supplier_order 844 844 0 0.00 0 7062 18 0 0
v_cancel_write_off 2014 2014 0 0.00 0 4487 24 0 0
v_max_id_clo_ord 5584 5584 0 0.00 0 492 5 0 0
v_max_id_clo_res 978 978 0 0.00 0 4055 21 0 0
v_max_id_ord_sup 5523 5523 0 0.00 0 5526 8 0 0
v_max_non_paid_invoice 1593 1593 0 0.00 0 826 7 0 0
v_max_non_paid_realizn 3973 3973 0 0.00 0 1068 8 0 0
v_min_id_clo_ord 5584 5584 0 0.00 0 3514 9 0 0
v_min_id_clo_res 978 978 0 0.00 0 1173 11 0 0
v_min_id_ord_sup 5523 5523 0 0.00 0 4533 8 0 0
v_min_non_paid_invoice 5804 5804 0 0.00 0 10299 21 0 0
v_min_non_paid_realizn 7164 7164 0 0.00 0 8005 17 0 0
v_our_firm 10602 10602 0 0.00 0 62 4 0 0
v_reserve_write_off 20388 20388 0 0.00 0 2401 10 0 0
x_lock_depdocs_on_canc_invoice 10829 10829 0 0.00 0 19138 143 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 7062 339 0 0
x_make_qstorno_1000_1200 5586 5586 0 0.00 3 26054 1251 0 0
x_make_qstorno_1200_2000 5530 5530 0 0.00 3 79542 2620 0 0
x_make_qstorno_2100_3300 473585 473585 0 0.00 0 130769 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-10-2022 13:38:52 to 04-10-2022 13:38:56.

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
11:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:27 16184 8192000000 9698132400 9990090752 774479360 8.637 100 88 173 0 803808032 7723200 21605472
11:09:48 24025 8192000000 9745299328 10049396736 818256624 9.081 100 88 174 0 850766640 11155312 21354704
11:11:18 16284 8192000000 9789680352 10106425344 853812560 9.438 100 87 174 0 892936320 18162256 20961504
11:12:42 9729 8192000000 9848380256 10180624384 914687632 10.044 100 73 141 0 955593568 25973456 14932480
11:13:56 5232 8192000000 9914900400 10256953344 961236496 10.501 100 86 173 0 1027415712 47965072 18214144
11:15:05 2503 8192000000 9977299632 10328772608 1004996096 10.927 100 92 182 0 1087826368 64055328 18774944
11:16:11 1601 8192000000 10003880880 10365382656 1029169040 11.160 100 90 172 0 1115248640 69222016 16857584
11:17:14 1523 8192000000 10030481936 10401583104 1042775712 11.291 100 96 187 0 1149470656 88592864 18102080
11:18:17 857 8192000000 10033178224 10407280640 1061256576 11.469 100 98 195 1 1155300160 74520528 19523056
11:19:19 3705 8192000000 10042544784 10416746496 1068327200 11.536 100 90 171 2 1163132912 79366560 15439152
11:20:24 3253 8192000000 10035906864 10425999360 1079267952 11.640 100 72 137 1 1165152944 72712480 13172512
11:21:28 1325 8192000000 10070746656 10453331968 1080957328 11.657 100 96 182 1 1184196224 85312080 17926816
11:22:30 1539 8192000000 10068814736 10457554944 1085591856 11.701 100 86 162 1 1191497520 89638624 16267040
11:23:33 1136 8192000000 10065448672 10450440192 1092928176 11.770 100 96 177 2 1186846784 75726448 18192160
11:24:35 1646 8192000000 10060337184 10447810560 1095755872 11.797 100 80 157 2 1179645072 67861520 16027680
11:25:38 3670 8192000000 10057751088 10452037632 1096456352 11.804 100 69 127 0 1184267888 74533568 13277968
11:26:43 989 8192000000 10074981408 10460704768 1100320848 11.841 100 95 190 0 1192571056 73657696 18592512
11:27:45 1158 8192000000 10068786688 10461794304 1106320576 11.898 100 90 171 4 1185367440 61535488 17511376
11:28:47 1165 8192000000 10065064544 10456035328 1101101952 11.848 100 97 183 0 1185713120 66325488 18285680
11:29:49 1233 8192000000 10037372384 10422693888 1089706112 11.740 100 96 178 4 1161658928 52821936 19130880
11:30:52 2697 8192000000 10008249088 10391121920 1067020928 11.524 99 63 119 3 1127475904 47915936 12539040
11:31:55 3883 8192000000 10009829312 10392821760 1066286576 11.517 100 77 137 1 1135499360 55359136 13853648
11:33:01 988 8192000000 9989641088 10360025088 1039832864 11.263 100 96 181 3 1111976096 53224240 18918992
11:34:04 896 8192000000 9978193680 10345566208 1025067552 11.121 100 94 182 2 1095593088 51772944 18752592
11:35:05 1054 8192000000 9991687472 10361126912 1018961344 11.062 100 97 182 0 1114612000 76208336 19442320
11:36:07 959 8192000000 9987683072 10358562816 1025859328 11.129 100 88 164 1 1107123120 64336624 16927168
11:37:09 2686 8192000000 9995032480 10369253376 1041786880 11.282 99 82 145 1 1113995984 58590928 13618176
11:38:22 14759 8192000000 10027015888 10414469120 1073786080 11.588 100 60 111 2 1142004016 57489200 10728736
11:39:42 16579 8192000000 10070614432 10460979200 1089876160 11.741 100 72 142 0 1184644112 80420192 14347760
11:41:18 2146 8192000000 10086988288 10477518848 1100500064 11.842 100 73 135 0 1207036080 92230272 14305744
11:42:27 19511 8192000000 10125763952 10526232576 1116483808 11.994 100 63 121 0 1231024288 102184576 12355904
11:43:58 10932 8192000000 10159792784 10572222464 1138904768 12.205 100 61 118 0 1277624640 125662848 13057024
11:45:12 3487 8192000000 10203806064 10603319296 1147860272 12.289 100 75 146 1 1312346704 148236592 16249840
11:46:24 5347 8192000000 10239679360 10639400960 1160942192 12.412 100 81 153 1 1349636912 171100688 17594032
11:47:33 1082 8192000000 10271038384 10672332800 1171876784 12.514 100 95 181 1 1383367632 190502624 20988224
11:48:36 1652 8192000000 10281968784 10689101824 1177819856 12.570 100 77 150 2 1393360528 198260976 17279696
11:49:46 1384 8192000000 10332247584 10742571008 1192433088 12.706 100 73 136 0 1446245008 238830480 14981440
11:50:52 901 8192000000 10369204336 10775023616 1199793104 12.774 100 91 170 2 1483684144 264914208 18976832
11:51:58 1175 8192000000 10406695040 10816307200 1199812096 12.775 100 91 171 0 1515742080 296938320 18991664
11:53:04 2741 8192000000 10445358992 10870059008 1210370592 12.873 100 75 144 0 1557382992 329861808 17150592
11:54:09 1169 8192000000 10478310688 10889060352 1205690320 12.829 100 98 195 0 1585872784 357299312 22883152
11:55:14 2192 8192000000 10529468752 10943385600 1216479152 12.929 100 87 170 1 1637531248 400336288 20715808
11:56:17 901 8192000000 10539765984 10987126784 1205493056 12.827 100 98 185 0 1646484608 418451568 22539984
11:57:21 1577 8192000000 10512787280 10937323520 1190640528 12.689 100 84 157 1 1619907872 409993024 19274320
11:58:25 869 8192000000 10553306176 10994188288 1193738944 12.718 100 83 157 2 1658394208 445333072 19322192
11:59:31 2402 8192000000 10535743984 10999791616 1189068176 12.675 100 66 129 1 1645269248 439551824 16649248
12:00:41 5013 8192000000 10491371584 10968203264 1195271200 12.732 100 44 90 0 1604282192 397394064 11616928
12:01:51 6045 8192000000 10493989440 10960384000 1189345648 12.677 100 49 96 0 1605265136 404052976 11866512
12:03:03 28220 8192000000 10487063360 10931478528 1154269504 12.350 99 35 70 1 1580734816 417009312 9456000
12:04:51 19715 8192000000 10383278688 10935869440 1127153520 12.095 98 28 57 0 1476953616 342232224 7567872
12:06:30 23128 8192000000 10241857152 10767859712 1128737504 12.109 99 20 39 0 1322150880 188186880 5226496
12:07:58 47133 8192000000 10095200128 10533847040 1115602816 11.985 97 25 46 0 1166136048 46369216 4164016
12:09:54 86293 8192000000 10092958432 10590482432 1094331856 11.784 97 20 40 0 1178997136 80158240 4507040
12:12:25 15067 8192000000 10075646528 10505768960 1134127744 12.160 100 47 93 2 1173194752 29867808 9199200
12:13:44 37717 8192000000 10065157504 10555420672 1122465664 12.050 99 15 28 0 1188490752 63070144 2954944
12:15:24 55708 8192000000 10124163680 10558156800 1123363952 12.059 99 52 101 0 1193714976 59880592 10470432
12:18:01 51578 8192000000 10090675168 10524794880 1120295696 12.030 99 45 84 1 1206680144 77197360 9187088
12:19:53 18305 8192000000 10095926688 10527444992 1135696976 12.175 100 56 106 0 1196156768 49383024 11076768
12:21:15 17648 8192000000 10118872176 10566877184 1143135184 12.245 100 38 74 1 1237507504 86439840 7932480
12:22:35 35273 8192000000 10176367952 10609098752 1145918400 12.271 100 33 65 0 1259431952 106692320 6821232
12:24:11 32795 8192000000 10196142256 10686484480 1133905792 12.158 98 28 56 0 1297100048 156204384 6989872
12:25:44 82855 8192000000 10221267088 10682740736 1156230576 12.368 100 23 45 0 1330869488 169341888 5297024
12:28:08 77546 8192000000 10158552352 10676625408 1103632000 11.872 97 15 31 0 1256230672 148773024 3825648
12:30:29 46509 8192000000 10089056512 10560294912 1136313840 12.181 100 24 48 0 1228214656 86653600 5247216
12:32:16 33812 8192000000 10103186672 10582474752 1136766192 12.185 100 17 32 0 1227764880 87404912 3593776
12:33:59 31054 8192000000 10088433600 10529574912 1134955040 12.168 99 38 75 2 1180979616 38144016 7880560
12:35:33 5777 8192000000 10113824704 10534547456 1131641376 12.137 99 72 136 0 1197706160 51963984 14100800
12:36:41 44642 8192000000 10183638016 10622201856 1134458080 12.163 99 34 68 0 1247893216 106324752 7110384
12:38:28 60323 8192000000 10102425120 10601873408 1121818192 12.044 98 15 31 0 1228861632 104078960 2964480
12:40:32 116567 8192000000 10059051856 10505162752 1096163536 11.801 96 28 56 0 1140255856 38184640 5907680
12:43:29 44413 8192000000 10086687088 10549800960 1128362720 12.106 99 31 62 0 1194390864 59226848 6801296
12:45:17 63755 8192000000 10085908128 10566021120 1136869968 12.186 100 17 34 0 1188250528 47892784 3487776
12:47:28 55918 8192000000 10084426224 10533904384 1096337888 11.803 96 19 36 1 1149575072 49317344 3919840
12:50:11 40350 8192000000 10078374928 10551156736 1132941776 12.149 100 37 69 2 1214499120 74163056 7394288
12:51:52 11312 8192000000 10059170656 10497347584 1128446208 12.107 99 41 82 0 1157182608 20963504 7772896
12:53:08 14752 8192000000 10104108848 10541662208 1133042144 12.150 100 54 102 1 1209810624 67236032 9532448
12:54:24 14929 8192000000 10117027584 10551304192 1117030304 11.999 98 45 90 0 1193090560 66975040 9085216
12:55:40 35904 8192000000 10094767472 10519977984 1121452432 12.041 99 39 78 0 1172165872 43205424 7508016
12:57:23 56446 8192000000 10100147488 10561740800 1110912224 11.941 98 39 77 0 1200895792 81971200 8012368
12:59:21 30516 8192000000 10155313248 10606772224 1135061936 12.169 100 50 96 0 1260099904 115865728 9172240
13:00:55 131060 8192000000 10055754912 10531704832 1092893504 11.770 97 17 34 0 1164415648 68054320 3467824
13:04:07 17852 8192000000 10053010720 10501074944 1104764448 11.883 99 38 76 0 1147060912 33584672 8711792
13:05:28 71519 8192000000 10071448400 10542575616 1087719744 11.721 97 7 14 0 1147324272 58090784 1513744
13:07:40 48360 8192000000 10066589680 10555703296 1115003120 11.980 100 16 31 0 1170766016 51899008 3863888
13:09:37 62386 8192000000 10072708352 10518962176 1110844320 11.940 98 17 33 0 1145078336 31135760 3098256
13:11:41 39740 8192000000 10102793392 10531856384 1113431344 11.965 98 33 65 1 1166735488 47187664 6116480
13:13:23 37347 8192000000 10083451600 10524422144 1118982304 12.017 99 15 30 0 1154210864 32286128 2942432
13:15:06 66990 8192000000 10112352832 10551013376 1119258080 12.020 98 36 70 0 1176851456 50646480 6946896
13:17:29 48133 8192000000 10115630656 10556547072 1092489072 11.766 96 32 64 0 1191191072 92105216 6596784
13:19:18 73438 8192000000 10101800368 10563977216 1104367168 11.879 98 17 34 0 1134589456 26940784 3281504
13:21:36 38667 8192000000 10075199648 10526851072 1110488480 11.937 99 28 54 0 1161419376 45511760 5419136
13:23:16 97476 8192000000 10092922240 10553675776 1128420672 12.106 100 21 40 0 1219876544 87268176 4187696
13:26:14 48050 8192000000 10083135200 10560925696 1129149344 12.113 100 10 20 0 1170967776 39835968 1982464
13:28:24 91203 8192000000 10076317088 10542608384 1086115824 11.706 96 16 32 0 1136364880 46274304 3974752
13:31:05 34836 8192000000 10123108224 10579681280 1111855360 11.950 98 30 58 2 1199960480 81091648 7013472
13:32:49 32726 8192000000 10156919056 10604969984 1140370160 12.219 100 28 56 0 1256207408 109487456 6349792
13:34:22 43587 8192000000 10137081168 10580094976 1112968288 11.961 97 30 59 0 1225305760 106013168 6324304
13:36:06 58044 8192000000 10177442752 10636554240 1106090672 11.895 97 31 62 0 1244592624 132205376 6296576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-10-2022 13:38:58 to 04-10-2022 13:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 152 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:03, from 04-10-2022 13:39:15 to 04-10-2022 13:39:18.

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          216053
MON$OLDEST_ACTIVE               223407
MON$OLDEST_SNAPSHOT             223407
MON$NEXT_TRANSACTION            223407
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                       2037280
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2854 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2854 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2854 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2854 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 ---
:112: 
:113: mscs-test-161.budzdorov.ru    Tue Oct  4 13:37:32 2022
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:115: 
--- end of diff output ---

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

04.10.22 13:39:19. 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-10-04 13:39:21 No severe PSQL-related problems occured
04.10.22 13:39:23. End of report.