Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.10.2022 10: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 15313796 kB
MemAvailable 30542204 kB
Buffers 0 kB
Cached 16422252 kB
SwapCached 2076 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-12 10:37:21
DTS_END                         2022-10-12 10:37:21.6140
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/49026
TRN_ID                          215561
ATT_ID                          581
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: 8751.16 22850 1050139
customer order: creation 164.12 465 19694
customer order: refuse 13.43 401 1612
order to supplier: creation 43.29 1343 5195
order to supplier: removal 3.26 178112 391
invoice (draft): creation 42.75 3074 5130
invoice (draft): removal 48.77 1073 5852
invoice accept: apply 39.96 28521 4795
invoice accept: cancel 44.21 1157 5305
customer reserve: creation 3734.10 273 448092
customer reserve: removal 4333.02 103 519962
realization accept: apply 77.56 287 9307
realization accept: cancel 84.28 167 10113
payment from customer: creation 55.96 173 6715
payment from customer: removal 5.10 122 612
payment to supplier: creation 46.15 207 5538
payment to supplier: removal 3.48 135 418
service: total inventory turnovers 4.05 3818 486
service: total monetary turnovers 6.73 356 807
service: aggregate perf. data 0.93 14557 111
service: refresh index statistics 0.03 222660 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2022 10:40:12 to 12-10-2022 10:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6471
TEST_TIME 2 6300
TEST_TIME 3 6436
TEST_TIME 4 5738
TEST_TIME 5 5936
TEST_TIME 6 5998
TEST_TIME 7 5980
TEST_TIME 8 5928
TEST_TIME 9 5920
TEST_TIME 10 6155
TEST_TIME 11 6236
TEST_TIME 12 6289
TEST_TIME 13 6509
TEST_TIME 14 6477
TEST_TIME 15 6532
TEST_TIME 16 6737
TEST_TIME 17 6848
TEST_TIME 18 6981
TEST_TIME 19 7425
TEST_TIME 20 7759
TEST_TIME 21 7939
TEST_TIME 22 7996
TEST_TIME 23 8011
TEST_TIME 24 8336
TEST_TIME 25 8623
TEST_TIME 26 8933
TEST_TIME 27 9208
TEST_TIME 28 9663
TEST_TIME 29 10238
TEST_TIME 30 10429
TEST_TIME 31 10648
TEST_TIME 32 11381
TEST_TIME 33 11281
TEST_TIME 34 10986
TEST_TIME 35 10896
TEST_TIME 36 10879
TEST_TIME 37 10885
TEST_TIME 38 10694
TEST_TIME 39 10663
TEST_TIME 40 10491
TEST_TIME 41 10370
TEST_TIME 42 10303
TEST_TIME 43 10283
TEST_TIME 44 10176
TEST_TIME 45 10060
TEST_TIME 46 9953
TEST_TIME 47 9891
TEST_TIME 48 9702
TEST_TIME 49 9805
TEST_TIME 50 9689
TEST_TIME 51 9540
TEST_TIME 52 9477
TEST_TIME 53 9387
TEST_TIME 54 9372
TEST_TIME 55 9406
TEST_TIME 56 9288
TEST_TIME 57 9352
TEST_TIME 58 9328
TEST_TIME 59 9335
TEST_TIME 60 9276
TEST_TIME 61 9306
TEST_TIME 62 9280
TEST_TIME 63 9287
TEST_TIME 64 9295
TEST_TIME 65 9314
TEST_TIME 66 9335
TEST_TIME 67 9341
TEST_TIME 68 9306
TEST_TIME 69 9292
TEST_TIME 70 9418
TEST_TIME 71 9392
TEST_TIME 72 9393
TEST_TIME 73 9401
TEST_TIME 74 9378
TEST_TIME 75 9332
TEST_TIME 76 9295
TEST_TIME 77 9209
TEST_TIME 78 9163
TEST_TIME 79 9175
TEST_TIME 80 9121
TEST_TIME 81 9090
TEST_TIME 82 9030
TEST_TIME 83 8947
TEST_TIME 84 8851
TEST_TIME 85 8980
TEST_TIME 86 8993
TEST_TIME 87 9022
TEST_TIME 88 9015
TEST_TIME 89 9031
TEST_TIME 90 9027
TEST_TIME 91 9026
TEST_TIME 92 9062
TEST_TIME 93 9070
TEST_TIME 94 9053
TEST_TIME 95 9044
TEST_TIME 96 9142
TEST_TIME 97 9162
TEST_TIME 98 9114
TEST_TIME 99 9086
TEST_TIME 100 9032
TEST_TIME 101 9032
TEST_TIME 102 8967
TEST_TIME 103 8948
TEST_TIME 104 8915
TEST_TIME 105 8906
TEST_TIME 106 8939
TEST_TIME 107 8899
TEST_TIME 108 8899
TEST_TIME 109 8893
TEST_TIME 110 8856
TEST_TIME 111 8826
TEST_TIME 112 8864
TEST_TIME 113 8846
TEST_TIME 114 8912
TEST_TIME 115 8936
TEST_TIME 116 8922
TEST_TIME 117 8873
TEST_TIME 118 8847
TEST_TIME 119 8837
TEST_TIME 120 8767

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2022 10:40:16 to 12-10-2022 10:40:19.

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 1462447 1462447 0 0.00 0 34232 15 0 0
doc_list_biud 1462434 1462434 0 0.00 0 91087 42 0 0
sp_add_invoice_to_stock 4900 4795 105 2.14 53 241775 28522 0 105
sp_cancel_adding_invoice 5305 5305 0 0.00 6 112223 1157 0 0
sp_cancel_client_order 1612 1612 0 0.00 5 12455 401 0 0
sp_cancel_customer_reserve 519962 519962 0 0.00 1 26318 104 0 0
sp_cancel_pay_from_customer 612 612 0 0.00 4 6216 123 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 5660 136 0 0
sp_cancel_supplier_invoice 5864 5852 12 0.20 3 94913 1073 0 12
sp_cancel_supplier_order 391 391 0 0.00 15 1421545 178112 0 0
sp_cancel_write_off 10113 10113 0 0.00 2 10231 167 0 0
sp_client_order 19694 19694 0 0.00 5 22619 466 0 0
sp_customer_reserve 448092 448092 0 0.00 0 76206 273 0 0
sp_fill_shopping_cart 30002 30002 0 0.00 1 19285 79 0 0
sp_fill_shopping_cart_clo_res 448067 448067 0 0.00 0 12345 20 0 0
sp_get_clo_for_invoice 4757 4757 0 0.00 0 57408 354 0 0
sp_kill_cost_storno 16448 16448 0 0.00 0 34216 73 0 0
sp_kill_qstorno_ret_qs2qd 1056280 1056280 0 0.00 0 91085 45 0 0
sp_kill_qty_storno 557296 557296 0 0.00 0 91086 103 0 0
sp_lock_selected_doc 19624 19624 0 0.00 0 8389 8 0 0
sp_make_cost_storno 26317 26317 0 0.00 0 19173 83 0 0
sp_multiply_rows_for_pdistr 26314 26314 0 0.00 0 10115 40 0 0
sp_multiply_rows_for_qdistr 19694 19694 0 0.00 0 21897 180 0 0
sp_pay_from_customer 6715 6715 0 0.00 5 14568 173 0 0
sp_pay_to_supplier 5538 5538 0 0.00 6 18063 207 0 0
sp_payment_common 12252 12252 0 0.00 2 14534 51 0 0
sp_qd_handle_on_cancel_clo 1612 1612 0 0.00 1 12436 323 0 0
sp_qd_handle_on_invoice_upd_sts 10061 10061 0 0.00 0 21787 513 0 0
sp_qd_handle_on_reserve_upd_sts 9305 9305 0 0.00 0 20216 68 0 0
sp_reserve_write_off 9307 9307 0 0.00 8 20420 287 0 0
sp_supplier_invoice 5130 5130 0 0.00 8 92368 3074 0 0
sp_supplier_order 5195 5195 0 0.00 8 44495 1344 0 0
srv_aggregate_perf_data 111 111 0 0.00 2878 234513 14557 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 760 135609 37097 0 0
srv_find_qd_qs_mism 1003244 1003244 0 0.00 0 12751 12 0 0
srv_make_invnt_saldo 486 486 0 0.00 40 157698 3818 0 0
srv_make_money_saldo 807 807 0 0.00 1 12397 357 0 0
srv_recalc_idx_stat 4 4 0 0.00 77647 341569 222660 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4797 4797 0 0.00 0 10426 849 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 1 8308 348 0 0
t$perf-norm:sp_cancel_client_order 1612 1612 0 0.00 0 4229 27 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 3756 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 612 612 0 0.00 0 1641 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 2800 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 2526 35 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 102875 7060 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 6859 24 0 0
t$perf-norm:sp_client_order 19695 19695 0 0.00 0 11422 33 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 878 14 0 0
t$perf-norm:sp_pay_from_customer 6715 6715 0 0.00 0 6572 17 0 0
t$perf-norm:sp_pay_to_supplier 5540 5540 0 0.00 0 2566 16 0 0
t$perf-norm:sp_reserve_write_off 9307 9307 0 0.00 0 6894 23 0 0
t$perf-norm:sp_supplier_invoice 5130 5130 0 0.00 0 2994 25 0 0
t$perf-norm:sp_supplier_order 5195 5195 0 0.00 0 3983 30 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 611 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 32 3 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 6632 27 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 313 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 22 195 102 0 0
v_add_invoice_to_stock 9714 9504 210 2.16 0 6963 29 0 210
v_all_customers 36557 36557 0 0.00 0 65 3 0 0
v_all_suppliers 29002 29002 0 0.00 0 93 4 0 0
v_all_wares 39373 39373 0 0.00 0 59 3 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 5993 19 0 0
v_cancel_client_order 3224 3224 0 0.00 0 5851 17 0 0
v_cancel_customer_prepayment 1224 1224 0 0.00 0 6143 19 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 13725 29 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 5606 18 0 0
v_cancel_supplier_invoice 882 858 24 2.72 0 3588 26 0 24
v_cancel_supplier_order 782 782 0 0.00 0 3409 14 0 0
v_cancel_write_off 1958 1958 0 0.00 0 2432 24 0 0
v_max_id_clo_ord 5184 5184 0 0.00 0 3961 5 0 0
v_max_id_clo_res 949 949 0 0.00 0 11417 33 0 0
v_max_id_ord_sup 5126 5126 0 0.00 0 8414 11 0 0
v_max_non_paid_invoice 1347 1347 0 0.00 0 88 7 0 0
v_max_non_paid_realizn 3250 3250 0 0.00 0 8832 11 0 0
v_min_id_clo_ord 5184 5184 0 0.00 0 2646 8 0 0
v_min_id_clo_res 949 949 0 0.00 0 1180 10 0 0
v_min_id_ord_sup 5126 5126 0 0.00 0 7609 14 0 0
v_min_non_paid_invoice 5538 5538 0 0.00 0 8628 24 0 0
v_min_non_paid_realizn 6714 6714 0 0.00 0 8709 23 0 0
v_our_firm 9740 9740 0 0.00 0 62 4 0 0
v_reserve_write_off 18610 18610 0 0.00 0 13033 14 0 0
x_lock_depdocs_on_canc_invoice 10728 10728 0 0.00 0 16770 142 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 13213 369 0 0
x_make_qstorno_1000_1200 5195 5195 0 0.00 2 44474 1195 0 0
x_make_qstorno_1200_2000 5130 5130 0 0.00 4 92355 2843 0 0
x_make_qstorno_2100_3300 448091 448091 0 0.00 0 76202 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2022 10:40:20 to 12-10-2022 10:40:24.

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
08:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:26 18403 8192000000 9728380496 10031898624 808919248 8.987 100 83 165 0 835969488 6938096 20112144
08:09:53 9008 8192000000 9771720016 10083819520 846711856 9.367 100 88 175 0 879353232 12015440 20625936
08:11:10 17335 8192000000 9807914704 10124230656 875629872 9.656 100 91 182 0 916917424 20527776 20759776
08:12:40 6764 8192000000 9872027056 10203561984 917658528 10.073 100 90 174 0 980234464 43561424 19014512
08:13:54 3553 8192000000 9945803632 10289573888 956202192 10.452 100 93 183 1 1056991984 80925248 19864544
08:15:02 1958 8192000000 9959507232 10313814016 991909680 10.800 100 92 173 0 1075276672 66152080 17214912
08:16:06 1495 8192000000 9982169504 10343944192 1022373712 11.095 100 91 171 2 1101020560 60522928 18123920
08:17:08 1051 8192000000 10024672064 10393976832 1042789552 11.291 100 96 183 1 1140872288 78719680 19363056
08:18:10 1045 8192000000 10016327312 10395312128 1056001584 11.418 100 95 184 1 1143745808 69859024 17885200
08:19:13 11887 8192000000 10026332096 10400890880 1067340112 11.527 100 86 161 0 1146478592 64250384 14888096
08:20:25 1099 8192000000 10047471184 10433593344 1078422240 11.632 100 86 161 0 1159727120 64652032 16652848
08:21:28 1042 8192000000 10046685472 10434437120 1084397856 11.689 100 93 172 0 1174618752 72900416 17320480
08:22:30 1079 8192000000 10063649696 10451185664 1087412944 11.718 100 89 172 2 1185316496 79344304 18559248
08:23:31 1371 8192000000 10036498784 10429890560 1093411296 11.775 100 83 147 2 1155948528 47321696 15215536
08:24:35 996 8192000000 10067413696 10453913600 1101461168 11.852 100 96 182 0 1186200096 66401968 18336960
08:25:37 2206 8192000000 10059660512 10456576000 1103106096 11.867 100 77 154 2 1183735136 65672864 14956176
08:26:40 932 8192000000 10073149328 10463195136 1100352672 11.841 100 88 165 0 1194906976 77690112 16864192
08:27:42 1108 8192000000 10041580416 10430742528 1100581584 11.843 100 84 156 2 1160828288 44574624 15672080
08:28:45 1008 8192000000 10041901472 10431127552 1095487504 11.795 100 94 179 2 1165994048 50975120 19531424
08:29:47 1724 8192000000 10033829360 10424107008 1086252272 11.707 100 76 125 1 1158978128 59278960 13446896
08:30:50 1202 8192000000 10029620016 10411585536 1065209280 11.506 100 78 145 2 1146890112 67414576 14266256
08:31:53 1713 8192000000 10007869264 10404245504 1068133040 11.534 100 67 118 2 1131033424 50074448 12825936
08:32:56 980 8192000000 9971633616 10346123264 1040376288 11.268 99 89 172 2 1096668256 38640048 17651920
08:33:57 4755 8192000000 9982545472 10372374528 1033468416 11.202 99 77 144 4 1110060464 60450000 16142048
08:35:03 7061 8192000000 9984925568 10365419520 1031813440 11.186 99 68 123 3 1108124576 64384544 11926592
08:36:10 2108 8192000000 9981225648 10358345728 1038357472 11.249 100 78 145 2 1100061920 47157264 14547184
08:37:13 3618 8192000000 9970880192 10345889792 1032853120 11.196 99 64 108 1 1092238464 47967616 11417728
08:38:23 10683 8192000000 10028434240 10412204032 1065042608 11.505 100 71 143 0 1147139760 67416112 14681040
08:39:43 4477 8192000000 10040736304 10423824384 1085506688 11.700 100 74 141 1 1163331648 62754016 15070944
08:40:51 1301 8192000000 10074108112 10459746304 1104175552 11.877 100 92 178 2 1193482992 69392464 19914976
08:41:57 6066 8192000000 10121873312 10525347840 1118635088 12.014 100 90 172 2 1238564144 100671568 19257488
08:43:07 7038 8192000000 10139105616 10536099840 1126713296 12.090 100 65 121 0 1264210672 125088576 12408800
08:44:18 1055 8192000000 10190740016 10577633280 1144902256 12.262 100 92 165 3 1302238048 139574848 17760944
08:45:22 5089 8192000000 10232481344 10634551296 1157727008 12.382 100 71 142 0 1344587664 171251760 15608896
08:46:39 4591 8192000000 10257446608 10654208000 1162541408 12.427 100 78 152 1 1373834752 195400016 15893328
08:47:48 1453 8192000000 10302605024 10704728064 1175587776 12.549 100 94 171 9 1420760368 223781616 21390976
08:48:52 773 8192000000 10336882544 10742042624 1189110464 12.675 100 94 181 3 1453436672 243341088 20985120
08:49:55 1358 8192000000 10390740992 10792669184 1185669856 12.643 100 95 182 0 1501500336 295584512 20245968
08:51:00 824 8192000000 10406813376 10808954880 1192261216 12.704 100 98 188 0 1515362128 300336032 22764880
08:52:04 1850 8192000000 10466098864 10872463360 1197257392 12.751 100 84 168 1 1573821216 357031200 19532624
08:53:10 2720 8192000000 10509149104 10931576832 1200630224 12.782 99 86 169 0 1616546016 395947392 19968400
08:54:17 1209 8192000000 10512324560 10950250496 1208151680 12.852 100 95 186 0 1616498464 385433136 22913648
08:55:23 862 8192000000 10557889664 10979844096 1214745776 12.913 100 99 195 1 1661799584 422761408 24292400
08:56:25 754 8192000000 10553488848 11024875520 1209733776 12.867 100 97 194 0 1659166192 426248736 23183680
08:57:27 1169 8192000000 10558190544 11015385088 1209487696 12.864 100 96 186 2 1666863552 434304976 23070880
08:58:31 1008 8192000000 10646426720 11079073792 1221966336 12.980 100 92 184 1 1751996208 508653472 21376400
08:59:35 4220 8192000000 10629355088 11075145728 1205214384 12.825 100 68 132 0 1733216544 511355904 16646256
09:00:43 2515 8192000000 10650687920 11122163712 1208514000 12.855 100 56 112 0 1759022752 535252064 15256688
09:01:55 1990 8192000000 10653142832 11097223168 1193752544 12.718 99 74 148 2 1750288992 538257104 18279344
09:03:11 8876 8192000000 10576207728 11056939008 1182974640 12.618 100 39 78 0 1690407328 496677616 10755072
09:04:21 13831 8192000000 10533718352 11050414080 1177453936 12.566 100 35 70 0 1641442256 454649920 9338400
09:05:40 32748 8192000000 10426004208 10960211968 1118304480 12.011 97 29 58 0 1561434464 435335600 7794384
09:07:20 33526 8192000000 10299747296 10771611648 1132136896 12.142 100 16 32 0 1440738976 304494768 4107312
09:08:55 6656 8192000000 10182561536 10711666688 1151805472 12.326 100 52 100 1 1315929792 152797616 11326704
09:10:09 78292 8192000000 10157807344 10611494912 1149651088 12.306 99 27 53 0 1242197040 86602576 5943376
09:12:50 21860 8192000000 10142303648 10600718336 1146539616 12.277 99 37 67 1 1244440592 90229184 7671792
09:14:31 74619 8192000000 10081580304 10542993408 1130565232 12.127 99 24 44 0 1179785248 44485072 4734944
09:16:46 87980 8192000000 10060132560 10509217792 1079615184 11.644 94 17 31 0 1122158320 39711440 2831696
09:19:27 116662 8192000000 10061842448 10498916352 1057057712 11.428 93 25 46 1 1103814928 42592624 4164592
09:22:26 61431 8192000000 10083683968 10527502336 1135615632 12.174 100 28 55 0 1202365728 60985408 5764688
09:24:29 71051 8192000000 10099697920 10554380288 1130671584 12.128 100 17 34 1 1191462192 57037072 3753536
09:26:54 81882 8192000000 10047143744 10495500288 1100002736 11.838 97 16 32 1 1141596448 37797920 3795792
09:29:17 45702 8192000000 10113360928 10555445248 1111886224 11.950 98 33 66 0 1208173072 88871584 7415264
09:31:08 35237 8192000000 10182401696 10640728064 1138998000 12.206 100 27 49 0 1295521104 150970912 5552192
09:32:44 43477 8192000000 10155786544 10623041536 1131470944 12.135 99 19 38 0 1252674352 116370832 4832576
09:34:32 94953 8192000000 10083515776 10614030336 1117191424 12.000 99 17 34 0 1210516192 89029552 4295216
09:37:43 26008 8192000000 10075147904 10518204416 1120295264 12.030 99 54 102 0 1170028624 38158816 11574544
09:39:09 67582 8192000000 10076572672 10520489984 1102606528 11.862 97 23 44 0 1132088832 24475696 5006608
09:41:19 43613 8192000000 10065573952 10506616832 1108639104 11.920 98 19 38 0 1151189664 38255600 4294960
09:43:11 42622 8192000000 10065255536 10500673536 1111290800 11.945 98 38 74 1 1164906592 46018368 7597424
09:44:58 58314 8192000000 10108423392 10571194368 1110932048 11.941 98 28 56 0 1202562096 86054304 5575744
09:47:50 52222 8192000000 10091259920 10540240896 1142392352 12.238 100 27 51 0 1174379776 27052256 4935168
09:49:44 71760 8192000000 10099571408 10540171264 1118044512 12.009 97 21 42 0 1150829536 28907808 3877216
09:52:00 135609 8192000000 10070096352 10517573632 1101877584 11.855 96 29 56 0 1137424352 30131232 5415536
09:55:18 18665 8192000000 10095817408 10541281280 1134610064 12.165 99 45 86 1 1202729136 58378768 9740304
09:56:40 42399 8192000000 10064506480 10513969152 1135163264 12.170 99 16 28 0 1163493712 25734704 2595744
09:58:27 39308 8192000000 10099250832 10556637184 1157161824 12.377 100 13 25 0 1200204704 40255360 2787520
10:00:09 91447 8192000000 10139563712 10585731072 1128776336 12.110 98 17 34 1 1226196320 93635168 3784816
10:02:53 47190 8192000000 10085122288 10548645888 1109715664 11.930 97 30 58 0 1178553712 61981664 6856384
10:04:48 49357 8192000000 10087804656 10525757440 1127399568 12.097 99 29 56 1 1177023888 43819216 5805104
10:06:42 51911 8192000000 10049311440 10500763648 1096315232 11.803 96 27 52 0 1144400576 43507968 4577376
10:08:36 37025 8192000000 10101554112 10544799744 1110227696 11.935 98 38 76 0 1177934256 60337424 7369136
10:10:15 30084 8192000000 10066481536 10493440000 1116919952 11.998 99 37 69 1 1166705296 42066224 7719120
10:11:46 11652 8192000000 10102072368 10524966912 1128004896 12.103 100 58 116 2 1201994176 62524720 11464560
10:12:58 42071 8192000000 10119987552 10587660288 1131035504 12.131 100 34 69 0 1214613536 76916560 6661472
10:14:42 41436 8192000000 10109302288 10534907904 1107269424 11.907 98 36 72 0 1185102064 70318320 7514320
10:16:32 63774 8192000000 10092386800 10540474368 1126271808 12.086 100 43 73 0 1172666000 38832192 7562000
10:18:37 91429 8192000000 10083837440 10548076544 1128440416 12.107 100 10 20 0 1185104144 54409680 2254048
10:23:02 79036 8192000000 10031115856 10516078592 1030297008 11.171 92 12 24 0 1078487392 45541760 2648624
10:25:21 45199 8192000000 10047733872 10486108160 1082247744 11.669 97 19 39 0 1108365680 21907424 4210512
10:27:07 24380 8192000000 10081396464 10505183232 1115368032 11.983 99 44 87 0 1160780848 36527744 8885072
10:28:34 33061 8192000000 10158778112 10597306368 1113889952 11.969 98 42 85 0 1241895072 119301440 8703680
10:30:07 64068 8192000000 10084067712 10584772608 1117481952 12.003 99 23 46 1 1208971040 86798400 4690688
10:32:17 30890 8192000000 10107614576 10541744128 1134924656 12.168 100 51 102 1 1193995664 48300176 10770832
10:33:51 62726 8192000000 10114253184 10540077056 1123676144 12.062 99 57 114 0 1205643296 70634160 11332992
10:35:57 92765 8192000000 9858715216 10279415808 944060176 10.333 83 19 38 0 988238592 40001344 4177072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-10-2022 10:40:26 to 12-10-2022 10:40:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-10-2022 10:40:43 to 12-10-2022 10:40:45.

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          215560
MON$OLDEST_ACTIVE               216234
MON$OLDEST_SNAPSHOT             216234
MON$NEXT_TRANSACTION            216234
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                       2055008
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2858 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2858 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2858 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2858 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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Wed Oct 12 10:38:21 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
--- end of diff output ---

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

12.10.22 10:40:46. 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-12 10:40:49 No severe PSQL-related problems occured
12.10.22 10:40:50. End of report.