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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15055604 kB
MemAvailable 30441384 kB
Buffers 0 kB
Cached 16678908 kB
SwapCached 3028 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 2023-03-06 13:37:25
DTS_END                         2023-03-06 13:37:25.4290
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/49586
TRN_ID                          218980
ATT_ID                          635
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: 8971.37 23268 1076564
customer order: creation 169.48 464 20338
customer order: refuse 14.48 412 1738
order to supplier: creation 44.77 1439 5372
order to supplier: removal 3.36 153135 403
invoice (draft): creation 44.34 3371 5321
invoice (draft): removal 49.96 1020 5995
invoice accept: apply 41.74 25649 5009
invoice accept: cancel 44.71 1221 5365
customer reserve: creation 3853.38 246 462405
customer reserve: removal 4401.14 92 528137
realization accept: apply 83.07 296 9968
realization accept: cancel 90.48 155 10857
payment from customer: creation 59.70 178 7164
payment from customer: removal 5.69 120 683
payment to supplier: creation 48.95 187 5874
payment to supplier: removal 3.77 122 452
service: total inventory turnovers 4.01 3989 481
service: total monetary turnovers 7.33 337 879
service: aggregate perf. data 0.98 14286 117
service: refresh index statistics 0.05 258648 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-03-2023 13:39:48 to 06-03-2023 13:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4471
TEST_TIME 2 4497
TEST_TIME 3 4438
TEST_TIME 4 4633
TEST_TIME 5 5870
TEST_TIME 6 5915
TEST_TIME 7 6083
TEST_TIME 8 6122
TEST_TIME 9 6161
TEST_TIME 10 6260
TEST_TIME 11 6728
TEST_TIME 12 6933
TEST_TIME 13 6975
TEST_TIME 14 7351
TEST_TIME 15 7526
TEST_TIME 16 7422
TEST_TIME 17 7488
TEST_TIME 18 7705
TEST_TIME 19 7852
TEST_TIME 20 8037
TEST_TIME 21 7984
TEST_TIME 22 8038
TEST_TIME 23 8154
TEST_TIME 24 7988
TEST_TIME 25 7825
TEST_TIME 26 7980
TEST_TIME 27 8729
TEST_TIME 28 8962
TEST_TIME 29 8920
TEST_TIME 30 9465
TEST_TIME 31 9565
TEST_TIME 32 9795
TEST_TIME 33 9926
TEST_TIME 34 10129
TEST_TIME 35 10502
TEST_TIME 36 10490
TEST_TIME 37 10724
TEST_TIME 38 10721
TEST_TIME 39 10680
TEST_TIME 40 10521
TEST_TIME 41 10549
TEST_TIME 42 10669
TEST_TIME 43 10636
TEST_TIME 44 10448
TEST_TIME 45 10253
TEST_TIME 46 10085
TEST_TIME 47 10038
TEST_TIME 48 9884
TEST_TIME 49 9861
TEST_TIME 50 9810
TEST_TIME 51 9814
TEST_TIME 52 9740
TEST_TIME 53 9703
TEST_TIME 54 9659
TEST_TIME 55 9557
TEST_TIME 56 9417
TEST_TIME 57 9412
TEST_TIME 58 9393
TEST_TIME 59 9330
TEST_TIME 60 9275
TEST_TIME 61 9207
TEST_TIME 62 9267
TEST_TIME 63 9257
TEST_TIME 64 9307
TEST_TIME 65 9342
TEST_TIME 66 9353
TEST_TIME 67 9422
TEST_TIME 68 9407
TEST_TIME 69 9380
TEST_TIME 70 9441
TEST_TIME 71 9451
TEST_TIME 72 9444
TEST_TIME 73 9398
TEST_TIME 74 9315
TEST_TIME 75 9343
TEST_TIME 76 9286
TEST_TIME 77 9233
TEST_TIME 78 9190
TEST_TIME 79 9133
TEST_TIME 80 9065
TEST_TIME 81 9085
TEST_TIME 82 9108
TEST_TIME 83 9106
TEST_TIME 84 9055
TEST_TIME 85 9053
TEST_TIME 86 9028
TEST_TIME 87 9041
TEST_TIME 88 9053
TEST_TIME 89 9124
TEST_TIME 90 9220
TEST_TIME 91 9269
TEST_TIME 92 9260
TEST_TIME 93 9194
TEST_TIME 94 9137
TEST_TIME 95 9132
TEST_TIME 96 9109
TEST_TIME 97 9054
TEST_TIME 98 9055
TEST_TIME 99 9013
TEST_TIME 100 8979
TEST_TIME 101 9014
TEST_TIME 102 9020
TEST_TIME 103 9024
TEST_TIME 104 8992
TEST_TIME 105 8977
TEST_TIME 106 8933
TEST_TIME 107 8978
TEST_TIME 108 9143
TEST_TIME 109 9159
TEST_TIME 110 9152
TEST_TIME 111 9125
TEST_TIME 112 9170
TEST_TIME 113 9239
TEST_TIME 114 9204
TEST_TIME 115 9163
TEST_TIME 116 9137
TEST_TIME 117 9121
TEST_TIME 118 9058
TEST_TIME 119 9046
TEST_TIME 120 8986

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-03-2023 13:39:52 to 06-03-2023 13:39:55.

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 1501503 1501503 0 0.00 0 37028 13 0 0
doc_list_biud 1501491 1501491 0 0.00 0 91369 39 0 0
sp_add_invoice_to_stock 5136 5009 127 2.47 36 184872 25650 0 127
sp_cancel_adding_invoice 5365 5365 0 0.00 6 77819 1221 0 0
sp_cancel_client_order 1738 1738 0 0.00 6 15564 412 0 0
sp_cancel_customer_reserve 528137 528137 0 0.00 1 91371 92 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 4322 120 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 3 6352 123 0 0
sp_cancel_supplier_invoice 6003 5995 8 0.13 3 53322 1020 0 8
sp_cancel_supplier_order 403 403 0 0.00 18 1538775 153136 0 0
sp_cancel_write_off 10857 10857 0 0.00 2 9562 155 0 0
sp_client_order 20338 20338 0 0.00 6 25593 465 0 0
sp_customer_reserve 462405 462405 0 0.00 1 66454 246 0 0
sp_fill_shopping_cart 31014 31014 0 0.00 1 18149 84 0 0
sp_fill_shopping_cart_clo_res 462381 462381 0 0.00 0 15662 17 0 0
sp_get_clo_for_invoice 4977 4977 0 0.00 0 19609 251 0 0
sp_kill_cost_storno 17357 17357 0 0.00 0 36206 70 0 0
sp_kill_qstorno_ret_qs2qd 1073529 1073529 0 0.00 0 91367 41 0 0
sp_kill_qty_storno 567434 567434 0 0.00 0 91369 94 0 0
sp_lock_selected_doc 20737 20737 0 0.00 0 9409 8 0 0
sp_make_cost_storno 27982 27982 0 0.00 0 13709 75 0 0
sp_multiply_rows_for_pdistr 27979 27979 0 0.00 0 8497 41 0 0
sp_multiply_rows_for_qdistr 20338 20338 0 0.00 0 11685 168 0 0
sp_pay_from_customer 7164 7164 0 0.00 6 12494 178 0 0
sp_pay_to_supplier 5874 5874 0 0.00 6 9036 188 0 0
sp_payment_common 13038 13038 0 0.00 2 8701 45 0 0
sp_qd_handle_on_cancel_clo 1738 1738 0 0.00 1 15556 341 0 0
sp_qd_handle_on_invoice_upd_sts 10338 10338 0 0.00 0 29475 491 0 0
sp_qd_handle_on_reserve_upd_sts 9966 9966 0 0.00 0 37103 77 0 0
sp_reserve_write_off 9968 9968 0 0.00 8 37485 296 0 0
sp_supplier_invoice 5321 5321 0 0.00 7 144571 3371 0 0
sp_supplier_order 5372 5372 0 0.00 8 97720 1439 0 0
srv_aggregate_perf_data 117 117 0 0.00 2531 143270 14287 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 741 153528 32634 0 0
srv_find_qd_qs_mism 1027390 1027390 0 0.00 0 37026 11 0 0
srv_make_invnt_saldo 481 481 0 0.00 67 80771 3989 0 0
srv_make_money_saldo 879 879 0 0.00 1 13317 338 0 0
srv_recalc_idx_stat 6 6 0 0.00 114193 476590 258648 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 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 5012 5012 0 0.00 0 26785 891 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 10057 539 0 0
t$perf-norm:sp_cancel_client_order 1738 1738 0 0.00 0 1793 27 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 5259 51 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1508 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 735 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 2020 39 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 79858 6239 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 510 16 0 0
t$perf-norm:sp_client_order 20338 20338 0 0.00 0 7858 35 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 1157 15 0 0
t$perf-norm:sp_pay_from_customer 7164 7164 0 0.00 0 11331 20 0 0
t$perf-norm:sp_pay_to_supplier 5874 5874 0 0.00 0 8220 22 0 0
t$perf-norm:sp_reserve_write_off 9968 9968 0 0.00 0 24774 25 0 0
t$perf-norm:sp_supplier_invoice 5321 5321 0 0.00 0 5695 32 0 0
t$perf-norm:sp_supplier_order 5372 5372 0 0.00 0 3289 30 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 282 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 131 6 0 0
t$perf-norm:srv_make_invnt_saldo 481 481 0 0.00 0 596 14 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 3366 10 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 6 151 54 0 0
v_add_invoice_to_stock 10194 9940 254 2.49 0 7182 25 0 254
v_all_customers 37750 37750 0 0.00 0 92 4 0 0
v_all_suppliers 29814 29814 0 0.00 0 70 4 0 0
v_all_wares 40673 40673 0 0.00 0 186 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 994 17 0 0
v_cancel_client_order 3476 3476 0 0.00 0 1928 10 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 2435 12 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 1 1508 13 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 3455 13 0 0
v_cancel_supplier_invoice 864 848 16 1.85 0 4804 30 0 16
v_cancel_supplier_order 806 806 0 0.00 0 1668 14 0 0
v_cancel_write_off 1976 1976 0 0.00 0 2260 25 0 0
v_max_id_clo_ord 5371 5371 0 0.00 0 4226 6 0 0
v_max_id_clo_res 999 999 0 0.00 0 5430 25 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 2932 6 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 278 8 0 0
v_max_non_paid_realizn 3608 3608 0 0.00 0 5784 9 0 0
v_min_id_clo_ord 5371 5371 0 0.00 0 5104 11 0 0
v_min_id_clo_res 999 999 0 0.00 0 5114 17 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 4281 8 0 0
v_min_non_paid_invoice 5874 5874 0 0.00 0 6320 15 0 0
v_min_non_paid_realizn 7163 7163 0 0.00 0 4904 18 0 0
v_our_firm 10034 10034 0 0.00 0 49 5 0 0
v_reserve_write_off 19928 19928 0 0.00 0 3875 11 0 0
x_lock_depdocs_on_canc_invoice 10936 10936 0 0.00 0 20486 118 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 20896 382 0 0
x_make_qstorno_1000_1200 5372 5372 0 0.00 2 91805 1262 0 0
x_make_qstorno_1200_2000 5321 5321 0 0.00 3 142364 3151 0 0
x_make_qstorno_2100_3300 462405 462405 0 0.00 0 66451 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-03-2023 13:39:57 to 06-03-2023 13:40:01.

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:29 938 8192000000 8930673776 8986714112 11742592 0.143 4 2 4 0 12354752 94992 517168
11:08:32 18207 8192000000 9724484624 10023878656 810768256 9.005 100 89 178 0 839671184 7884880 21018048
11:09:55 13316 8192000000 9766255408 10077425664 845287536 9.353 100 88 176 0 877277296 11457360 20532400
11:11:12 10339 8192000000 9796320688 10114818048 870287040 9.603 100 83 164 2 906020880 16743856 18989984
11:12:26 14593 8192000000 9848842272 10180108288 906233136 9.960 100 83 165 0 958544112 33946816 18364160
11:13:48 7604 8192000000 9911153952 10250829824 949836560 10.389 100 88 176 0 1020454960 51749376 18869024
11:14:59 2797 8192000000 9958502672 10311245824 993933696 10.820 100 88 170 0 1075307984 64124192 17250096
11:16:05 1626 8192000000 10027324096 10385555456 1020729888 11.079 100 92 181 2 1136573984 97983424 17860672
11:17:10 1599 8192000000 10022576176 10392604672 1031537344 11.183 100 92 174 0 1140914544 92613920 16763280
11:18:13 966 8192000000 10053452464 10428936192 1057223008 11.430 100 95 186 2 1171969392 95449824 19296560
11:19:14 1210 8192000000 10038153120 10419097600 1066676976 11.520 100 93 183 1 1159356560 74369120 18310464
11:20:17 1030 8192000000 10068602480 10453876736 1074212688 11.592 100 95 182 0 1193218544 100630080 18375776
11:21:20 1131 8192000000 10037858112 10424877056 1075795024 11.607 100 79 153 1 1161172624 69718608 15658992
11:22:22 920 8192000000 10030544720 10416148480 1086270640 11.707 100 87 170 0 1150780304 47649088 16860576
11:23:23 1163 8192000000 10051039216 10438475776 1090396144 11.746 100 87 160 3 1170230896 63566368 16268384
11:24:26 1122 8192000000 10066731936 10452488192 1091769952 11.759 100 96 185 0 1184933872 74518096 18645824
11:25:28 2705 8192000000 10060066272 10453467136 1095070784 11.791 100 76 141 2 1180767264 70824208 14872272
11:26:31 1211 8192000000 10067848112 10456440832 1098057760 11.819 100 100 184 0 1192416224 76228480 18129984
11:27:34 2337 8192000000 10048600096 10446524416 1102010240 11.857 100 68 131 0 1185948160 71101696 12836224
11:28:38 1120 8192000000 10059009616 10450681856 1105364928 11.889 100 96 185 6 1179525312 53837984 20322400
11:29:40 1205 8192000000 10066947904 10453262336 1100814336 11.845 100 97 183 1 1188147760 68524864 18808560
11:30:42 9548 8192000000 10037422880 10441883648 1084229488 11.688 99 45 90 0 1161796976 68277008 9290480
11:31:53 884 8192000000 10025660224 10408779776 1066313344 11.517 100 90 160 2 1146605472 62294816 17997312
11:32:55 2308 8192000000 10004346368 10390704128 1044781184 11.311 99 67 123 2 1133402752 75703456 12918112
11:33:58 6355 8192000000 9983916368 10370162688 1030548080 11.174 99 61 120 1 1112299376 69531840 12219456
11:35:06 840 8192000000 9958173360 10333876224 1027129696 11.141 100 86 161 1 1082283040 39338112 15815232
11:36:08 2057 8192000000 9976959536 10351939584 1025685168 11.127 99 78 147 2 1093645904 53734448 14226288
11:37:11 6321 8192000000 9994385136 10381451264 1050993312 11.370 100 62 114 2 1116453936 53953744 11506880
11:38:26 9762 8192000000 10029369056 10412900352 1073915520 11.589 100 76 134 1 1147066624 57574448 15576656
11:39:39 1905 8192000000 10025944016 10411966464 1067908096 11.532 99 75 139 1 1144726240 62747152 14070992
11:40:47 11179 8192000000 10063708864 10450890752 1084214320 11.688 100 70 130 0 1177448496 79805136 13429040
11:42:02 1475 8192000000 10083892000 10480713728 1094085696 11.781 100 78 154 0 1202331168 91113696 17131776
11:43:14 1445 8192000000 10099205552 10485960704 1107143776 11.905 100 98 185 1 1219531520 91679328 20708416
11:44:22 6448 8192000000 10110843936 10506838016 1110923936 11.941 100 81 158 1 1229206848 101736896 16546016
11:45:38 961 8192000000 10128554880 10518880256 1119714976 12.024 100 90 170 0 1242777808 105019248 18043584
11:46:42 2082 8192000000 10169045024 10565914624 1128878768 12.111 100 90 172 0 1284470800 135828336 19763696
11:47:50 4194 8192000000 10176211648 10588348416 1144300688 12.256 100 70 133 0 1303410976 143432160 15678128
11:48:57 950 8192000000 10232996096 10637295616 1161731520 12.419 100 97 188 4 1345791856 161905440 22154896
11:50:00 766 8192000000 10270076768 10668933120 1169056288 12.488 100 97 186 2 1380973008 190491264 21425456
11:51:04 821 8192000000 10266594832 10686177280 1176250608 12.555 100 90 172 0 1376975568 182084896 18640064
11:52:08 998 8192000000 10326628400 10727653376 1178369952 12.575 100 98 191 3 1430709456 230698672 21640832
11:53:11 1372 8192000000 10321036816 10727038976 1167701312 12.475 100 92 182 2 1432426400 244448032 20277056
11:54:19 1319 8192000000 10374332528 10788352000 1172945840 12.524 100 93 181 1 1478628048 285198720 20483488
11:55:22 1106 8192000000 10381537648 10798182400 1175009920 12.544 100 92 178 0 1487034144 290823152 21201072
11:56:28 1033 8192000000 10378634464 10793148416 1180340128 12.593 100 92 181 1 1484328272 283237280 20750864
11:57:33 1544 8192000000 10410787472 10827554816 1181490112 12.604 100 70 136 0 1520600752 322641152 16469488
11:58:37 2547 8192000000 10412821392 10834112512 1177623024 12.568 100 58 112 1 1521876800 330458944 13794832
11:59:45 1033 8192000000 10453101824 10878468096 1172661872 12.522 100 86 170 0 1561418656 368819424 19937360
12:00:53 3072 8192000000 10445531472 10874720256 1156152048 12.367 100 65 126 0 1544955552 373007008 15796496
12:01:58 2669 8192000000 10479343904 10897383424 1160333168 12.406 100 64 121 0 1587471856 411753152 15385536
12:03:05 730 8192000000 10520751968 10956107776 1146601104 12.278 100 88 174 1 1624012192 455762272 21648816
12:04:08 13648 8192000000 10454677312 10942558208 1158245840 12.387 100 46 92 0 1566078448 395528656 12303952
12:05:32 3183 8192000000 10418796720 10876772352 1159727792 12.401 100 57 113 0 1521285664 347191632 14366240
12:06:47 2034 8192000000 10431352912 10859708416 1150492336 12.314 100 64 125 0 1528444176 362931072 15020768
12:07:57 19965 8192000000 10354593408 10797174784 1154461200 12.351 100 31 62 0 1456290624 293986432 7842992
12:09:42 22812 8192000000 10267439488 10714103808 1139140016 12.207 100 29 58 1 1382606048 236962816 6503216
12:11:19 45436 8192000000 10185760784 10698518528 1118196016 12.010 98 14 28 0 1287346016 165950256 3199744
12:13:09 43711 8192000000 10138305088 10577268736 1118151424 12.010 99 12 24 0 1212738336 92376384 2210528
12:15:10 118092 8192000000 10082936928 10524696576 1066308640 11.517 93 29 57 1 1106858848 34924112 5626096
12:18:09 49305 8192000000 10123199264 10596319232 1126577600 12.089 99 22 45 0 1216513184 85638560 4297024
12:20:03 153527 8192000000 10072829296 10532167680 1132166256 12.142 100 18 36 0 1187563840 51823360 3574224
12:23:40 20199 8192000000 10082720624 10517504000 1138149536 12.198 100 55 101 2 1179969200 30653184 11166480
12:25:05 19599 8192000000 10079140240 10505662464 1130607824 12.127 99 40 73 0 1169982080 31982160 7392096
12:26:28 45559 8192000000 10108783824 10559635456 1131953552 12.140 99 48 95 0 1200491040 59042864 9494624
12:28:28 66057 8192000000 10077502928 10535989248 1121810880 12.044 98 15 28 1 1161523600 36475184 3237536
12:30:35 16883 8192000000 10058145216 10527911936 1124663488 12.071 99 13 27 1 1169123216 41517648 2942080
12:31:52 22420 8192000000 10062037184 10514984960 1139052208 12.207 100 22 41 1 1167709120 25267296 3389616
12:33:15 120890 8192000000 10078380992 10585956352 1148293216 12.293 100 21 40 0 1246109248 93591984 4224048
12:36:27 26138 8192000000 10116058320 10568478720 1128522112 12.107 99 41 80 0 1198093040 60793328 8777600
12:37:59 62035 8192000000 10137754560 10598150144 1139855552 12.214 99 17 34 0 1221881024 78180624 3844848
12:40:01 46795 8192000000 10090622672 10548211712 1121906064 12.045 99 26 50 1 1191823360 64056576 5860720
12:41:49 30084 8192000000 10117213360 10579525632 1120444464 12.031 98 30 60 0 1187466496 60654384 6367648
12:43:23 49697 8192000000 10102031648 10577879040 1108954096 11.923 98 26 51 0 1213106160 98717040 5435024
12:45:25 11725 8192000000 10087000768 10521939968 1124404080 12.069 100 56 106 0 1173689568 38696304 10589184
12:46:38 9514 8192000000 10100102736 10524930048 1132825904 12.148 100 50 99 0 1192459392 50084640 9548848
12:47:47 36652 8192000000 10092035504 10547204096 1128353680 12.106 99 36 72 0 1194615312 59027664 7233968
12:49:29 18472 8192000000 10110311568 10551652352 1135279808 12.171 99 54 107 0 1202628336 56533296 10815232
12:50:50 121847 8192000000 10091717168 10595778560 1147163616 12.283 100 35 68 0 1235742672 81585440 6993616
12:54:01 49990 8192000000 10071694272 10530799616 1069706944 11.549 93 13 26 0 1122478144 49948384 2822816
12:56:01 56723 8192000000 10056338192 10508906496 1073122240 11.582 96 7 14 0 1130631408 56262080 1247088
12:57:59 60672 8192000000 10079094096 10568482816 1115902640 11.988 100 14 28 0 1191428752 72390352 3135760
13:00:14 39777 8192000000 10044475296 10487566336 1076702352 11.616 96 16 32 0 1117727712 37870272 3155088
13:02:00 30435 8192000000 10106275472 10560585728 1118578944 12.014 99 36 69 0 1202399008 76514912 7305152
13:03:30 25749 8192000000 10141815632 10568335360 1105333056 11.888 98 28 56 0 1207713136 96161392 6218688
13:04:56 30966 8192000000 10188564320 10626998272 1094992016 11.790 97 39 78 0 1265297552 162224704 8080832
13:06:29 37199 8192000000 10172636608 10645819392 1120250256 12.029 100 27 52 0 1268263264 142554032 5458976
13:08:07 79324 8192000000 10063042512 10493693952 1104452800 11.880 99 24 46 0 1175069936 65685440 4931696
13:10:29 107634 8192000000 10090727552 10536787968 1116590352 11.995 99 39 77 0 1193732560 69053920 8088288
13:13:18 36401 8192000000 10052061584 10533523456 1127378544 12.097 100 3 6 0 1162911232 34963936 568752
13:14:56 59299 8192000000 10055205664 10542919680 1132375584 12.144 100 9 18 0 1189238736 55485184 1377968
13:17:02 25075 8192000000 10091695744 10542919680 1144084624 12.254 100 30 60 0 1184120256 33391488 6644144
13:18:29 79725 8192000000 10060155184 10506883072 1111997328 11.951 97 21 40 1 1157330560 41267152 4066080
13:20:51 34664 8192000000 10163487408 10627969024 1159698416 12.400 100 21 42 1 1253006848 88534640 4773792
13:22:27 27110 8192000000 10188362528 10633297920 1147041872 12.282 99 32 64 0 1278134912 123954928 7138112
13:23:59 31699 8192000000 10239409632 10708062208 1155936528 12.365 99 38 76 0 1339228144 174755584 8536032
13:25:32 49248 8192000000 10103142224 10608021504 1117703616 12.005 97 13 26 0 1202577296 82092512 2781168
13:27:25 13546 8192000000 10089581936 10541576192 1110249456 11.935 98 38 72 0 1146808816 29123328 7436032
13:28:45 43515 8192000000 10173824800 10641338368 1134625200 12.165 100 35 68 2 1227253280 85293440 7334640
13:30:32 89233 8192000000 10086545904 10569789440 1103858448 11.874 97 11 22 1 1223453392 116711328 2883616
13:33:02 73012 8192000000 10057899648 10513190912 1096793040 11.807 97 16 32 1 1131011344 30780528 3437776
13:35:21 55988 8192000000 10068333168 10539008000 1107372032 11.908 98 9 18 0 1154347248 44888784 2086432
13:37:20 22027 8192000000 9241018304 9581531136 378494144 4.416 35 2 4 0 413494400 34690928 309328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-03-2023 13:40:03 to 06-03-2023 13:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 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 06-03-2023 13:40:20 to 06-03-2023 13:40:22.

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          219321
MON$OLDEST_ACTIVE               219322
MON$OLDEST_SNAPSHOT             219322
MON$NEXT_TRANSACTION            219322
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                       2062112
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2906 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2906 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2906 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2906 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 ---
:84: 
:85: mscs-test-161.budzdorov.ru    Mon Mar  6 13:38:01 2023
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:87: 
--- end of diff output ---

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

06.03.23 13:40:24. 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
2023-03-06 13:40:26 No severe PSQL-related problems occured
06.03.23 13:40:28. End of report.