Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2022 07: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 14450080 kB
MemAvailable 30084560 kB
Buffers 0 kB
Cached 17221156 kB
SwapCached 3344 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 07:37:23
DTS_END                         2022-12-20 07:37:23.1170
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36654
TRN_ID                          214951
ATT_ID                          601
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: 9288.97 25624 1114676
customer order: creation 165.43 484 19851
customer order: refuse 14.05 486 1686
order to supplier: creation 44.68 1503 5362
order to supplier: removal 3.48 201388 417
invoice (draft): creation 44.28 3275 5313
invoice (draft): removal 52.82 1110 6338
invoice accept: apply 41.04 25955 4925
invoice accept: cancel 47.99 1190 5759
customer reserve: creation 3872.28 240 464674
customer reserve: removal 4708.72 115 565046
realization accept: apply 80.97 286 9717
realization accept: cancel 88.26 209 10591
payment from customer: creation 55.86 183 6703
payment from customer: removal 5.19 129 623
payment to supplier: creation 48.97 216 5876
payment to supplier: removal 3.21 138 385
service: total inventory turnovers 3.83 4722 459
service: total monetary turnovers 6.98 439 838
service: aggregate perf. data 0.91 16523 109
service: refresh index statistics 0.03 253885 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-12-2022 07:38:30 to 20-12-2022 07:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6560
TEST_TIME 2 6189
TEST_TIME 3 5596
TEST_TIME 4 5710
TEST_TIME 5 6104
TEST_TIME 6 5901
TEST_TIME 7 5996
TEST_TIME 8 6234
TEST_TIME 9 6530
TEST_TIME 10 6727
TEST_TIME 11 6655
TEST_TIME 12 6693
TEST_TIME 13 7004
TEST_TIME 14 6868
TEST_TIME 15 6908
TEST_TIME 16 6892
TEST_TIME 17 7028
TEST_TIME 18 7198
TEST_TIME 19 7118
TEST_TIME 20 7126
TEST_TIME 21 7082
TEST_TIME 22 7159
TEST_TIME 23 7263
TEST_TIME 24 7175
TEST_TIME 25 7061
TEST_TIME 26 7157
TEST_TIME 27 7509
TEST_TIME 28 7554
TEST_TIME 29 7850
TEST_TIME 30 8213
TEST_TIME 31 8669
TEST_TIME 32 8976
TEST_TIME 33 9287
TEST_TIME 34 9306
TEST_TIME 35 9956
TEST_TIME 36 10298
TEST_TIME 37 10820
TEST_TIME 38 10958
TEST_TIME 39 10995
TEST_TIME 40 11002
TEST_TIME 41 10950
TEST_TIME 42 10975
TEST_TIME 43 10842
TEST_TIME 44 10662
TEST_TIME 45 10627
TEST_TIME 46 10651
TEST_TIME 47 10665
TEST_TIME 48 10656
TEST_TIME 49 10706
TEST_TIME 50 10558
TEST_TIME 51 10416
TEST_TIME 52 10276
TEST_TIME 53 10239
TEST_TIME 54 10226
TEST_TIME 55 10232
TEST_TIME 56 10091
TEST_TIME 57 10046
TEST_TIME 58 10049
TEST_TIME 59 9962
TEST_TIME 60 10109
TEST_TIME 61 10012
TEST_TIME 62 10102
TEST_TIME 63 10056
TEST_TIME 64 9972
TEST_TIME 65 9847
TEST_TIME 66 9845
TEST_TIME 67 9968
TEST_TIME 68 9904
TEST_TIME 69 9907
TEST_TIME 70 9896
TEST_TIME 71 9866
TEST_TIME 72 9821
TEST_TIME 73 9755
TEST_TIME 74 9703
TEST_TIME 75 9713
TEST_TIME 76 10058
TEST_TIME 77 10003
TEST_TIME 78 9960
TEST_TIME 79 9860
TEST_TIME 80 9825
TEST_TIME 81 9843
TEST_TIME 82 9781
TEST_TIME 83 9755
TEST_TIME 84 9703
TEST_TIME 85 9670
TEST_TIME 86 9623
TEST_TIME 87 9580
TEST_TIME 88 9499
TEST_TIME 89 9471
TEST_TIME 90 9468
TEST_TIME 91 9455
TEST_TIME 92 9477
TEST_TIME 93 9429
TEST_TIME 94 9478
TEST_TIME 95 9473
TEST_TIME 96 9499
TEST_TIME 97 9534
TEST_TIME 98 9513
TEST_TIME 99 9625
TEST_TIME 100 9623
TEST_TIME 101 9663
TEST_TIME 102 9612
TEST_TIME 103 9620
TEST_TIME 104 9579
TEST_TIME 105 9510
TEST_TIME 106 9506
TEST_TIME 107 9481
TEST_TIME 108 9468
TEST_TIME 109 9440
TEST_TIME 110 9378
TEST_TIME 111 9344
TEST_TIME 112 9330
TEST_TIME 113 9337
TEST_TIME 114 9320
TEST_TIME 115 9305
TEST_TIME 116 9316
TEST_TIME 117 9301
TEST_TIME 118 9322
TEST_TIME 119 9385
TEST_TIME 120 9315

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-12-2022 07:38:33 to 20-12-2022 07:38:37.

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 1541715 1541715 0 0.00 0 29909 14 0 0
doc_list_biud 1541703 1541703 0 0.00 0 32951 48 0 0
sp_add_invoice_to_stock 5050 4925 125 2.47 64 179463 25955 0 125
sp_cancel_adding_invoice 5759 5759 0 0.00 6 65461 1190 0 0
sp_cancel_client_order 1686 1686 0 0.00 6 12669 487 0 0
sp_cancel_customer_reserve 565046 565046 0 0.00 1 32955 115 0 0
sp_cancel_pay_from_customer 623 623 0 0.00 4 6435 129 0 0
sp_cancel_pay_to_supplier 385 385 0 0.00 4 2884 139 0 0
sp_cancel_supplier_invoice 6348 6338 10 0.15 2 25314 1110 0 10
sp_cancel_supplier_order 417 417 0 0.00 7 1535455 201389 0 0
sp_cancel_write_off 10591 10591 0 0.00 2 25108 209 0 0
sp_client_order 19851 19851 0 0.00 5 15477 484 0 0
sp_customer_reserve 464674 464674 0 0.00 1 111448 240 0 0
sp_fill_shopping_cart 30510 30510 0 0.00 1 17628 88 0 0
sp_fill_shopping_cart_clo_res 464642 464642 0 0.00 0 17350 20 0 0
sp_get_clo_for_invoice 4884 4884 0 0.00 0 27882 313 0 0
sp_kill_cost_storno 17358 17358 0 0.00 0 13320 85 0 0
sp_kill_qstorno_ret_qs2qd 1147438 1147438 0 0.00 0 32945 49 0 0
sp_kill_qty_storno 604436 604436 0 0.00 0 32950 113 0 0
sp_lock_selected_doc 20053 20053 0 0.00 0 9413 9 0 0
sp_make_cost_storno 27179 27179 0 0.00 0 29891 88 0 0
sp_multiply_rows_for_pdistr 27178 27178 0 0.00 0 7947 37 0 0
sp_multiply_rows_for_qdistr 19850 19850 0 0.00 0 15296 186 0 0
sp_pay_from_customer 6703 6703 0 0.00 5 17379 183 0 0
sp_pay_to_supplier 5876 5876 0 0.00 6 10710 217 0 0
sp_payment_common 12579 12579 0 0.00 2 6035 48 0 0
sp_qd_handle_on_cancel_clo 1686 1686 0 0.00 1 12659 396 0 0
sp_qd_handle_on_invoice_upd_sts 10642 10642 0 0.00 0 26724 556 0 0
sp_qd_handle_on_reserve_upd_sts 9716 9716 0 0.00 0 24621 64 0 0
sp_reserve_write_off 9717 9717 0 0.00 8 24651 287 0 0
sp_supplier_invoice 5313 5313 0 0.00 11 99475 3276 0 0
sp_supplier_order 5362 5362 0 0.00 7 35079 1503 0 0
srv_aggregate_perf_data 109 109 0 0.00 2702 233020 16523 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 734 120372 31327 0 0
srv_find_qd_qs_mism 1066298 1066298 0 0.00 0 15225 10 0 0
srv_make_invnt_saldo 459 459 0 0.00 54 119349 4722 0 0
srv_make_money_saldo 838 838 0 0.00 1 14942 440 0 0
srv_recalc_idx_stat 4 4 0 0.00 66619 396315 253885 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4933 4933 0 0.00 1 13737 988 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 1 8279 456 0 0
t$perf-norm:sp_cancel_client_order 1686 1686 0 0.00 0 6769 32 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 4503 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 623 623 0 0.00 0 313 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 385 385 0 0.00 0 520 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 355 355 0 0.00 0 1773 45 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 83718 7272 0 0
t$perf-norm:sp_cancel_write_off 927 927 0 0.00 0 1851 19 0 0
t$perf-norm:sp_client_order 19851 19851 0 0.00 0 11820 39 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 732 16 0 0
t$perf-norm:sp_pay_from_customer 6703 6703 0 0.00 0 9753 21 0 0
t$perf-norm:sp_pay_to_supplier 5876 5876 0 0.00 0 6670 25 0 0
t$perf-norm:sp_reserve_write_off 9717 9717 0 0.00 0 12853 25 0 0
t$perf-norm:sp_supplier_invoice 5313 5313 0 0.00 0 7753 35 0 0
t$perf-norm:sp_supplier_order 5362 5362 0 0.00 0 6131 33 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 593 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 337 11 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 3374 35 0 0
t$perf-norm:srv_make_money_saldo 838 838 0 0.00 0 353 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 10 49 29 0 0
v_add_invoice_to_stock 10014 9764 250 2.49 0 15184 31 0 250
v_all_customers 36822 36822 0 0.00 0 98 4 0 0
v_all_suppliers 30516 30516 0 0.00 0 136 4 0 0
v_all_wares 39698 39698 0 0.00 0 113 4 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 2158 22 0 0
v_cancel_client_order 3372 3372 0 0.00 0 4018 15 0 0
v_cancel_customer_prepayment 1246 1246 0 0.00 0 4698 17 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1943 11 0 0
v_cancel_payment_to_supplier 770 770 0 0.00 0 1067 12 0 0
v_cancel_supplier_invoice 730 710 20 2.73 0 1015 21 0 20
v_cancel_supplier_order 834 834 0 0.00 0 2700 13 0 0
v_cancel_write_off 1854 1854 0 0.00 0 3568 29 0 0
v_max_id_clo_ord 5358 5358 0 0.00 0 13454 8 0 0
v_max_id_clo_res 965 965 0 0.00 0 1746 14 0 0
v_max_id_ord_sup 5303 5303 0 0.00 0 5943 8 0 0
v_max_non_paid_invoice 1277 1277 0 0.00 0 74 8 0 0
v_max_non_paid_realizn 3253 3253 0 0.00 0 1555 8 0 0
v_min_id_clo_ord 5357 5357 0 0.00 0 5698 10 0 0
v_min_id_clo_res 965 965 0 0.00 0 2787 11 0 0
v_min_id_ord_sup 5303 5303 0 0.00 0 8604 11 0 0
v_min_non_paid_invoice 5876 5876 0 0.00 0 6027 18 0 0
v_min_non_paid_realizn 6703 6703 0 0.00 0 4967 20 0 0
v_our_firm 9776 9776 0 0.00 0 162 5 0 0
v_reserve_write_off 19432 19432 0 0.00 0 8262 13 0 0
x_lock_depdocs_on_canc_invoice 11742 11742 0 0.00 0 20806 133 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 8295 456 0 0
x_make_qstorno_1000_1200 5362 5362 0 0.00 2 35068 1321 0 0
x_make_qstorno_1200_2000 5313 5313 0 0.00 4 99411 3032 0 0
x_make_qstorno_2100_3300 464674 464674 0 0.00 0 111435 208 0 0

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

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
05:07:27 935 8192000000 8918973360 8973529088 3900160 0.047 2 1 2 0 3997776 61840 35776
05:08:29 34142 8192000000 9725578864 10024587264 798110368 8.877 100 88 176 0 827560688 7926928 21523392
05:10:12 22091 8192000000 9768352256 10076696576 845889776 9.359 100 89 178 0 880769264 13744032 21135456
05:11:39 17000 8192000000 9794980048 10112991232 862988848 9.530 100 84 166 0 904412624 22319200 19104576
05:13:10 6922 8192000000 9866586832 10197815296 911123008 10.008 100 83 163 0 976283904 47248496 17912400
05:14:21 6496 8192000000 9906792592 10248376320 950520736 10.396 100 82 162 0 1017220784 49660464 17039584
05:15:37 1779 8192000000 9971834112 10329337856 998215392 10.861 100 91 179 1 1087784848 71514992 18054464
05:16:41 1146 8192000000 9991606624 10352373760 1020069936 11.073 100 97 182 1 1112808336 74388704 18349696
05:17:44 1444 8192000000 10008929360 10384220160 1043064720 11.294 100 92 180 1 1132933968 71731680 18137568
05:18:47 1028 8192000000 10027760560 10400964608 1057542992 11.433 100 94 180 1 1147088448 70663392 18882064
05:19:49 1011 8192000000 10029327760 10403094528 1063808176 11.493 100 94 175 1 1154730624 71897568 19024880
05:20:51 5586 8192000000 10030431104 10404720640 1071717056 11.568 100 92 166 3 1146179360 58197936 16264368
05:22:02 1636 8192000000 10020891568 10407190528 1075892576 11.608 100 76 148 1 1138811536 47766240 15152720
05:23:05 1105 8192000000 10054402432 10438447104 1079962688 11.647 100 87 165 1 1174040864 78260512 15817664
05:24:08 911 8192000000 10045722768 10433425408 1088137712 11.725 100 87 166 4 1169044368 64277952 16628704
05:25:10 1189 8192000000 10052926480 10444169216 1091114944 11.753 100 79 146 0 1179841600 73340704 15385952
05:26:12 1165 8192000000 10038420720 10423013376 1092792080 11.769 100 86 155 1 1157571216 48728656 16050480
05:27:14 1125 8192000000 10059277136 10445619200 1082290816 11.669 99 98 191 1 1178602416 77002432 19309168
05:28:16 939 8192000000 10054725088 10439864320 1086501360 11.709 100 91 174 1 1174654736 69289520 18863856
05:29:18 1014 8192000000 10068354592 10461306880 1096099344 11.801 100 88 161 2 1185558208 72347584 17111280
05:30:20 1196 8192000000 10043561056 10427535360 1082286880 11.669 100 96 184 4 1163815392 62961712 18566800
05:31:22 1234 8192000000 10067250160 10450804736 1074362800 11.594 100 96 187 0 1188893392 95751328 18779264
05:32:24 1483 8192000000 10004143936 10379214848 1053792992 11.397 100 76 137 5 1118660448 50865632 14001824
05:33:27 3070 8192000000 10010986016 10386137088 1043360144 11.297 100 82 155 0 1127270320 67742080 16168096
05:34:31 1070 8192000000 10000729360 10382622720 1053109232 11.390 100 85 168 2 1126154992 54992784 18052976
05:35:33 1413 8192000000 10000297088 10376511488 1022172176 11.093 100 86 165 0 1124418224 85322656 16923392
05:36:36 1188 8192000000 9988900912 10363084800 1031514880 11.183 100 90 170 1 1114106752 64666464 17925408
05:37:41 7083 8192000000 9988791552 10369871872 1056026864 11.418 100 78 141 0 1103363968 34168576 13168528
05:39:09 5949 8192000000 10027684976 10405376000 1067242144 11.526 100 81 159 1 1150841920 67112768 16487008
05:40:18 3751 8192000000 10056815344 10444959744 1075530800 11.605 100 73 140 0 1168960528 78753680 14676048
05:41:29 2457 8192000000 10078495968 10463797248 1089907376 11.742 100 80 146 1 1199533760 92099856 17526528
05:42:37 1633 8192000000 10100453472 10498809856 1102961824 11.866 100 69 131 1 1223594384 107002384 13630176
05:43:41 5444 8192000000 10141775360 10532712448 1115017504 11.980 100 76 148 2 1253065120 121351008 16696608
05:44:53 2023 8192000000 10169182640 10563280896 1131889600 12.139 100 91 175 1 1284088640 133128352 19070688
05:46:00 966 8192000000 10230712416 10624077824 1141332944 12.228 100 96 183 2 1342742432 181637888 19771600
05:47:03 2489 8192000000 10219729152 10638139392 1145149744 12.264 100 91 180 2 1334806496 169883888 19772864
05:48:12 1206 8192000000 10236409984 10635726848 1156338784 12.369 100 85 157 3 1344911744 171660592 16912368
05:49:17 3406 8192000000 10265670384 10681761792 1165316848 12.453 100 84 158 4 1380633328 197381824 17934656
05:50:26 721 8192000000 10289013024 10717237248 1180715376 12.597 100 92 176 0 1401274464 200704336 19854752
05:51:30 3447 8192000000 10344978832 10747527168 1192407184 12.706 100 88 167 1 1454639744 242825600 19406960
05:52:41 2475 8192000000 10379769072 10795892736 1197873488 12.757 100 89 172 0 1482820176 264634304 20312384
05:53:47 907 8192000000 10436317328 10842374144 1210542384 12.874 100 96 191 0 1544729344 312226880 21960080
05:54:51 3053 8192000000 10449143648 10884079616 1207994640 12.851 100 90 176 0 1559209936 330672656 20542640
05:56:01 1278 8192000000 10491223184 10905251840 1229063216 13.045 100 95 187 1 1598095664 346475872 22556576
05:57:06 1535 8192000000 10541608704 10963259392 1231695536 13.070 100 95 186 1 1650314272 395184976 23433760
05:58:12 912 8192000000 10586718992 10998042624 1234606096 13.097 100 96 189 0 1691868848 434354640 22908112
05:59:17 993 8192000000 10619617120 11037192192 1231247552 13.066 100 96 187 0 1722947328 469132000 22567776
06:00:20 865 8192000000 10644700720 11075862528 1229953504 13.054 100 96 190 1 1750595232 496780112 23861616
06:01:29 1676 8192000000 10710790928 11123384320 1227705136 13.033 100 100 200 0 1815779664 563752160 24322368
06:02:37 1029 8192000000 10751853632 11165003776 1219643712 12.958 100 92 181 0 1854229408 611911536 22674160
06:03:40 832 8192000000 10764775552 11204890624 1201357296 12.789 100 96 191 1 1880076864 654282528 24437040
06:04:42 1017 8192000000 10805052992 11255496704 1200370656 12.780 100 80 161 1 1911178720 689637808 21170256
06:05:47 4312 8192000000 10741927504 11211976704 1183573344 12.624 99 64 124 2 1856640320 656285744 16781232
06:06:58 6876 8192000000 10671567520 11160936448 1192967344 12.711 100 59 118 1 1786248096 577659472 15621280
06:08:25 11295 8192000000 10642758304 11160018944 1174602784 12.540 99 53 101 1 1740690912 552847296 13240832
06:09:39 12282 8192000000 10560360432 11108610048 1164658288 12.447 99 40 80 0 1683652320 507966080 11027952
06:10:59 20990 8192000000 10526431840 10995204096 1152397344 12.332 99 31 60 0 1631088816 470609888 8081584
06:12:22 20141 8192000000 10377676448 10874953728 1157567728 12.380 100 27 54 2 1490591104 326386752 6636624
06:13:49 44242 8192000000 10188730800 10667618304 1136723360 12.185 99 30 57 0 1339191600 194787744 7680496
06:16:05 55939 8192000000 10112402480 10600611840 1124195184 12.067 98 11 21 1 1232124288 105689408 2239696
06:18:21 120371 8192000000 10090350544 10584875008 1104965552 11.885 97 14 28 0 1156057216 47996768 3094896
06:21:25 26044 8192000000 10132306304 10561900544 1143129120 12.245 100 32 60 0 1210696368 61417792 6149456
06:22:51 66532 8192000000 10145890816 10613583872 1136547488 12.183 99 15 30 0 1237894480 98364672 2982320
06:24:59 53867 8192000000 10085409840 10535436288 1133779760 12.157 99 42 76 0 1160674960 18897200 7998000
06:26:56 48226 8192000000 10053339296 10509873152 1117644544 12.005 98 17 30 2 1145310576 23414480 4251552
06:28:50 68600 8192000000 10083358320 10523168768 1098216336 11.821 98 45 85 1 1174289344 67216960 8856048
06:31:00 42812 8192000000 10087645680 10524954624 1123641984 12.061 99 31 59 0 1177729888 48299328 5788576
06:32:52 78626 8192000000 10090869264 10510655488 1081314688 11.660 95 77 142 2 1147071648 47475936 18281024
06:35:14 12763 8192000000 10142252864 10584358912 1131131504 12.132 100 58 110 2 1245534960 101109728 13293728
06:36:28 13550 8192000000 10165813952 10593161216 1139681728 12.213 100 75 141 0 1252021648 94770544 17569376
06:37:43 32300 8192000000 10124224160 10614157312 1133906224 12.158 99 20 38 0 1213835296 76259184 3669888
06:39:16 35595 8192000000 10070382768 10540224512 1145904240 12.271 100 20 40 0 1213357344 63009040 4444064
06:40:53 41955 8192000000 10117691600 10582437888 1148674176 12.297 100 19 36 0 1203425824 51176096 3575552
06:42:37 111570 8192000000 10109150080 10629545984 1131505712 12.136 98 14 28 0 1228082928 93657008 2920208
06:45:36 15928 8192000000 10096652896 10536030208 1134238080 12.161 98 39 66 2 1183382944 41624928 7519936
06:46:59 35736 8192000000 10119138048 10573242368 1143635120 12.250 99 31 58 1 1191442976 41620368 6187488
06:48:41 22814 8192000000 10133582784 10576973824 1130509856 12.126 98 28 55 0 1209068016 72176576 6381584
06:50:06 96285 8192000000 10226300320 10670292992 1110765456 11.940 96 28 56 0 1285150640 167787504 6597680
06:52:59 49794 8192000000 10078659456 10629726208 1123681856 12.062 100 13 26 0 1241128016 113957472 3488688
06:54:49 41953 8192000000 10061346096 10496966656 1075127280 11.601 95 47 88 0 1122218320 38466704 8624336
06:56:36 98181 8192000000 10073133200 10537639936 1101782912 11.855 98 32 62 0 1180626528 72574384 6269232
06:59:16 14358 8192000000 10060357808 10524946432 1122828800 12.054 100 26 51 1 1171857632 44371360 4657472
07:00:31 32678 8192000000 10035997472 10500485120 1102816320 11.864 98 9 18 1 1143456816 38793824 1846672
07:02:04 44137 8192000000 10052552064 10500452352 1118368832 12.012 99 15 28 1 1144336016 23402128 2565056
07:03:50 58775 8192000000 10077997856 10542612480 1122374144 12.049 99 15 30 0 1167561120 41662112 3524864
07:05:52 75280 8192000000 10086019664 10572611584 1122722544 12.053 99 12 24 0 1189916848 64531760 2662544
07:08:07 69717 8192000000 10093477312 10542845952 1093573680 11.777 96 16 32 0 1152885968 56084688 3227600
07:10:18 36073 8192000000 10147689952 10587111424 1138044272 12.197 100 40 80 0 1226021856 80150848 7826736
07:11:54 31793 8192000000 10115096896 10558414848 1124740528 12.072 98 36 69 0 1206633056 74012480 7880048
07:13:27 29189 8192000000 10135033808 10611433472 1141067936 12.226 99 40 79 0 1235678848 85639968 8970944
07:14:59 38635 8192000000 10147403552 10586365952 1147635072 12.287 100 39 78 0 1226576192 71117056 7824064
07:16:40 62469 8192000000 10090876544 10543054848 1115328640 11.983 97 29 56 0 1149180032 28010944 5840448
07:18:44 23810 8192000000 10061528080 10500448256 1125979808 12.083 99 26 50 1 1165921552 34988208 4953536
07:20:11 9981 8192000000 10142683712 10568417280 1136037040 12.178 100 62 124 0 1224948224 76504608 12406576
07:21:23 109844 8192000000 10110532768 10585542656 1134633888 12.165 99 25 50 0 1214974832 74994256 5346688
07:24:17 49504 8192000000 10094945232 10598047744 1131609872 12.137 99 7 14 0 1191270720 58178016 1482832
07:26:07 42873 8192000000 10061704864 10523295744 1094806256 11.788 97 10 20 0 1128083200 30785328 2491616
07:27:50 95329 8192000000 10073174848 10562105344 1123882848 12.064 99 10 20 0 1192033760 66159760 1991152
07:30:26 52960 8192000000 10081497456 10536493056 1104631344 11.882 97 22 40 1 1151887488 43015200 4240944
07:32:27 37223 8192000000 10128706560 10579066880 1143147968 12.245 100 28 56 0 1224106864 75070000 5888896
07:34:09 29240 8192000000 10124636352 10576691200 1115598992 11.985 98 30 60 0 1221172880 99320224 6253664
07:35:42 58577 8192000000 10095982240 10614550528 1113432032 11.965 98 24 46 0 1215944112 97491216 5020864

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 07:38:44 to 20-12-2022 07:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2022 07:39:01 to 20-12-2022 07:39:03.

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          213048
MON$OLDEST_ACTIVE               215441
MON$OLDEST_SNAPSHOT             215441
MON$NEXT_TRANSACTION            215441
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                       2100096
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             765
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 07:39:04. 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 07:39:07 No severe PSQL-related problems occured
20.12.22 07:39:08. End of report.