Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.02.2023 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 15093884 kB
MemAvailable 30477772 kB
Buffers 0 kB
Cached 16636788 kB
SwapCached 3156 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-02-27 10:37:27
DTS_END                         2023-02-27 10:37:27.1660
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/52644
TRN_ID                          223624
ATT_ID                          607
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: 9091.68 26181 1091002
customer order: creation 175.68 479 21082
customer order: refuse 15.07 496 1808
order to supplier: creation 46.22 1450 5546
order to supplier: removal 3.48 171464 418
invoice (draft): creation 46.36 3353 5563
invoice (draft): removal 50.67 1074 6080
invoice accept: apply 42.35 27164 5082
invoice accept: cancel 45.48 1309 5457
customer reserve: creation 3961.26 257 475351
customer reserve: removal 4400.67 105 528080
realization accept: apply 85.01 281 10201
realization accept: cancel 87.08 165 10449
payment from customer: creation 60.33 187 7239
payment from customer: removal 6.12 132 734
payment to supplier: creation 49.79 207 5975
payment to supplier: removal 3.33 154 400
service: total inventory turnovers 3.95 4394 474
service: total monetary turnovers 7.93 371 952
service: aggregate perf. data 0.89 18076 107
service: refresh index statistics 0.03 292494 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-02-2023 10:40:01 to 27-02-2023 10:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7775
TEST_TIME 2 6280
TEST_TIME 3 6157
TEST_TIME 4 5619
TEST_TIME 5 5996
TEST_TIME 6 5704
TEST_TIME 7 5798
TEST_TIME 8 5800
TEST_TIME 9 5684
TEST_TIME 10 5453
TEST_TIME 11 5608
TEST_TIME 12 5803
TEST_TIME 13 6031
TEST_TIME 14 6082
TEST_TIME 15 6480
TEST_TIME 16 6485
TEST_TIME 17 6841
TEST_TIME 18 6859
TEST_TIME 19 6826
TEST_TIME 20 6941
TEST_TIME 21 7069
TEST_TIME 22 7198
TEST_TIME 23 7321
TEST_TIME 24 7508
TEST_TIME 25 7597
TEST_TIME 26 7660
TEST_TIME 27 7969
TEST_TIME 28 8423
TEST_TIME 29 8646
TEST_TIME 30 8901
TEST_TIME 31 9045
TEST_TIME 32 9260
TEST_TIME 33 10002
TEST_TIME 34 10188
TEST_TIME 35 10171
TEST_TIME 36 10051
TEST_TIME 37 10298
TEST_TIME 38 10466
TEST_TIME 39 10370
TEST_TIME 40 10234
TEST_TIME 41 10545
TEST_TIME 42 10541
TEST_TIME 43 10423
TEST_TIME 44 10329
TEST_TIME 45 10308
TEST_TIME 46 10119
TEST_TIME 47 10041
TEST_TIME 48 9888
TEST_TIME 49 9902
TEST_TIME 50 9951
TEST_TIME 51 9780
TEST_TIME 52 9729
TEST_TIME 53 9689
TEST_TIME 54 9674
TEST_TIME 55 9598
TEST_TIME 56 9735
TEST_TIME 57 9713
TEST_TIME 58 9610
TEST_TIME 59 9625
TEST_TIME 60 9577
TEST_TIME 61 9508
TEST_TIME 62 9461
TEST_TIME 63 9400
TEST_TIME 64 9579
TEST_TIME 65 9569
TEST_TIME 66 9567
TEST_TIME 67 9572
TEST_TIME 68 9539
TEST_TIME 69 9607
TEST_TIME 70 9591
TEST_TIME 71 9551
TEST_TIME 72 9572
TEST_TIME 73 9652
TEST_TIME 74 9651
TEST_TIME 75 9687
TEST_TIME 76 9653
TEST_TIME 77 9658
TEST_TIME 78 9553
TEST_TIME 79 9591
TEST_TIME 80 9496
TEST_TIME 81 9451
TEST_TIME 82 9430
TEST_TIME 83 9388
TEST_TIME 84 9409
TEST_TIME 85 9364
TEST_TIME 86 9321
TEST_TIME 87 9295
TEST_TIME 88 9214
TEST_TIME 89 9188
TEST_TIME 90 9171
TEST_TIME 91 9131
TEST_TIME 92 9193
TEST_TIME 93 9166
TEST_TIME 94 9205
TEST_TIME 95 9164
TEST_TIME 96 9171
TEST_TIME 97 9166
TEST_TIME 98 9210
TEST_TIME 99 9260
TEST_TIME 100 9258
TEST_TIME 101 9241
TEST_TIME 102 9206
TEST_TIME 103 9166
TEST_TIME 104 9184
TEST_TIME 105 9138
TEST_TIME 106 9102
TEST_TIME 107 9069
TEST_TIME 108 9101
TEST_TIME 109 9058
TEST_TIME 110 9072
TEST_TIME 111 9076
TEST_TIME 112 9093
TEST_TIME 113 9047
TEST_TIME 114 9021
TEST_TIME 115 9082
TEST_TIME 116 9153
TEST_TIME 117 9110
TEST_TIME 118 9122
TEST_TIME 119 9198
TEST_TIME 120 9122

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-02-2023 10:40:05 to 27-02-2023 10:40:09.

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 1528307 1528307 0 0.00 0 29656 14 0 0
doc_list_biud 1528292 1528292 0 0.00 0 32224 42 0 0
sp_add_invoice_to_stock 5193 5082 111 2.13 78 208642 27165 0 111
sp_cancel_adding_invoice 5457 5457 0 0.00 6 83680 1310 0 0
sp_cancel_client_order 1808 1808 0 0.00 6 16692 497 0 0
sp_cancel_customer_reserve 528080 528080 0 0.00 1 32372 105 0 0
sp_cancel_pay_from_customer 734 734 0 0.00 4 10390 132 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 4 10163 154 0 0
sp_cancel_supplier_invoice 6094 6080 14 0.22 3 27195 1074 0 14
sp_cancel_supplier_order 418 418 0 0.00 13 1392757 171465 0 0
sp_cancel_write_off 10449 10449 0 0.00 2 15020 166 0 0
sp_client_order 21082 21082 0 0.00 5 27435 480 0 0
sp_customer_reserve 475351 475351 0 0.00 0 127249 257 0 0
sp_fill_shopping_cart 32177 32177 0 0.00 2 12697 81 0 0
sp_fill_shopping_cart_clo_res 475314 475314 0 0.00 0 24029 18 0 0
sp_get_clo_for_invoice 5036 5036 0 0.00 0 29896 271 0 0
sp_kill_cost_storno 17040 17040 0 0.00 0 29644 79 0 0
sp_kill_qstorno_ret_qs2qd 1073107 1073107 0 0.00 0 28092 45 0 0
sp_kill_qty_storno 567528 567528 0 0.00 0 32224 105 0 0
sp_lock_selected_doc 21209 21209 0 0.00 0 8683 7 0 0
sp_make_cost_storno 28451 28451 0 0.00 0 17402 85 0 0
sp_multiply_rows_for_pdistr 28450 28450 0 0.00 0 9846 41 0 0
sp_multiply_rows_for_qdistr 21081 21081 0 0.00 0 14997 184 0 0
sp_pay_from_customer 7239 7239 0 0.00 5 12753 188 0 0
sp_pay_to_supplier 5975 5975 0 0.00 6 14653 207 0 0
sp_payment_common 13214 13214 0 0.00 2 13383 47 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 16675 409 0 0
sp_qd_handle_on_invoice_upd_sts 10493 10493 0 0.00 0 32222 581 0 0
sp_qd_handle_on_reserve_upd_sts 10200 10200 0 0.00 0 19829 60 0 0
sp_reserve_write_off 10201 10201 0 0.00 9 19899 281 0 0
sp_supplier_invoice 5563 5563 0 0.00 8 87037 3353 0 0
sp_supplier_order 5546 5546 0 0.00 8 34023 1450 0 0
srv_aggregate_perf_data 107 107 0 0.00 2547 124330 18076 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 702 149191 33662 0 0
srv_find_qd_qs_mism 1040754 1040754 0 0.00 0 22501 11 0 0
srv_make_invnt_saldo 474 474 0 0.00 62 98305 4395 0 0
srv_make_money_saldo 952 952 0 0.00 1 16811 372 0 0
srv_recalc_idx_stat 4 4 0 0.00 200135 480252 292494 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5088 5088 0 0.00 1 30454 953 0 0
t$perf-norm:sp_cancel_adding_invoice 408 408 0 0.00 1 6235 446 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 20499 48 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 2142 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 734 734 0 0.00 0 4343 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 4051 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 402 402 0 0.00 0 1075 40 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 1 109112 7571 0 0
t$perf-norm:sp_cancel_write_off 1082 1082 0 0.00 0 3099 24 0 0
t$perf-norm:sp_client_order 21083 21083 0 0.00 0 6668 36 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 4759 27 0 0
t$perf-norm:sp_pay_from_customer 7239 7239 0 0.00 0 5670 18 0 0
t$perf-norm:sp_pay_to_supplier 5975 5975 0 0.00 0 7875 23 0 0
t$perf-norm:sp_reserve_write_off 10201 10201 0 0.00 0 6018 24 0 0
t$perf-norm:sp_supplier_invoice 5564 5564 0 0.00 0 14281 38 0 0
t$perf-norm:sp_supplier_order 5546 5546 0 0.00 0 3591 34 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 1927 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 28 3 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 952 16 0 0
t$perf-norm:srv_make_money_saldo 952 952 0 0.00 0 303 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 196 84 0 0
v_add_invoice_to_stock 10288 10066 222 2.15 0 10421 31 0 222
v_all_customers 38892 38892 0 0.00 0 134 4 0 0
v_all_suppliers 31332 31332 0 0.00 0 136 4 0 0
v_all_wares 42157 42157 0 0.00 0 227 4 0 0
v_cancel_adding_invoice 816 816 0 0.00 0 1386 18 0 0
v_cancel_client_order 3616 3616 0 0.00 0 3354 15 0 0
v_cancel_customer_prepayment 1468 1468 0 0.00 0 10354 23 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 1 1462 14 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 6444 22 0 0
v_cancel_supplier_invoice 832 804 28 3.36 0 1881 28 0 28
v_cancel_supplier_order 836 836 0 0.00 0 2984 19 0 0
v_cancel_write_off 2164 2164 0 0.00 0 6808 29 0 0
v_max_id_clo_ord 5539 5539 0 0.00 0 398 5 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 6837 27 0 0
v_max_id_ord_sup 5555 5555 0 0.00 0 7581 8 0 0
v_max_non_paid_invoice 1401 1401 0 0.00 0 487 9 0 0
v_max_non_paid_realizn 3598 3598 0 0.00 0 3928 10 0 0
v_min_id_clo_ord 5539 5539 0 0.00 0 1650 9 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 3129 17 0 0
v_min_id_ord_sup 5555 5555 0 0.00 0 5192 9 0 0
v_min_non_paid_invoice 5975 5975 0 0.00 0 7064 15 0 0
v_min_non_paid_realizn 7238 7238 0 0.00 0 4003 17 0 0
v_our_firm 10544 10544 0 0.00 0 138 5 0 0
v_reserve_write_off 20399 20399 0 0.00 0 5290 12 0 0
x_lock_depdocs_on_canc_invoice 11135 11135 0 0.00 0 25588 133 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 14100 457 0 0
x_make_qstorno_1000_1200 5545 5545 0 0.00 2 34009 1278 0 0
x_make_qstorno_1200_2000 5563 5563 0 0.00 4 87023 3133 0 0
x_make_qstorno_2100_3300 475351 475351 0 0.00 0 127244 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-02-2023 10:40:10 to 27-02-2023 10:40:14.

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:30 2 8192000000 8799158688 8810713088 5009376 0.061 3 1 2 0 5110544 65392 35776
08:08:31 28894 8192000000 9695139680 9991139328 777289424 8.666 100 87 174 0 807001600 7822752 21889424
08:10:06 21266 8192000000 9754731680 10061848576 830752624 9.207 100 89 176 0 863508384 11360592 21395168
08:11:31 20999 8192000000 9790604864 10104868864 860790336 9.508 100 87 172 0 897801664 16956816 20054512
08:13:00 10334 8192000000 9852313520 10183565312 902166224 9.920 100 79 155 0 960795904 41647984 16981696
08:14:13 6716 8192000000 9910939792 10253221888 955319024 10.443 100 86 168 0 1024052048 51435136 17297888
08:15:25 2948 8192000000 9962403296 10311409664 979902384 10.683 100 92 182 1 1077511696 78459040 19150272
08:16:31 2122 8192000000 9975538096 10338668544 1017631408 11.049 100 96 178 6 1087148896 51922992 17594496
08:17:36 1078 8192000000 10025950624 10389483520 1034538064 11.212 100 93 183 1 1142575008 90236800 17800144
08:18:38 1257 8192000000 10027954304 10406752256 1052940176 11.389 100 97 187 1 1149752128 76956496 19855456
08:19:41 1114 8192000000 10045960304 10419879936 1065546560 11.510 100 91 169 0 1157859552 75288880 17024112
08:20:43 1085 8192000000 10049305808 10430263296 1071126928 11.563 100 90 170 0 1169532336 82091744 16313664
08:21:45 892 8192000000 10037453760 10419625984 1075607584 11.606 100 87 166 2 1157608096 65195808 16804704
08:22:47 1006 8192000000 10050893776 10435899392 1081195568 11.659 100 93 176 3 1170405664 70877488 18332608
08:23:49 1013 8192000000 10033642496 10420924416 1087314656 11.717 100 83 160 1 1156972512 53284688 16373168
08:24:52 1176 8192000000 10077147200 10466856960 1094290016 11.783 100 95 177 0 1198042464 85714976 18037472
08:25:54 1177 8192000000 10064302992 10450178048 1096836848 11.808 100 97 189 3 1190799936 74454496 19508592
08:26:56 1101 8192000000 10072290192 10457489408 1099862208 11.836 100 95 188 1 1193675568 73384784 20428576
08:27:59 1681 8192000000 10066574608 10455928832 1099659264 11.834 100 77 148 0 1182094896 66182224 16253408
08:29:02 1075 8192000000 10039218032 10435665920 1097536784 11.814 100 77 137 3 1164964592 51148160 16279648
08:30:03 3821 8192000000 10030805040 10426671104 1091643824 11.758 99 68 123 1 1153863440 49555104 12664512
08:31:08 9897 8192000000 10027386192 10414841856 1063899328 11.494 99 66 128 1 1151108976 74357200 12852448
08:32:20 1214 8192000000 10026072336 10405773312 1056334208 11.421 99 87 165 0 1143975936 70612912 17028816
08:33:22 1062 8192000000 10009729232 10385268736 1038377936 11.249 100 100 183 3 1132227632 75882400 17967296
08:34:25 1266 8192000000 10008592176 10383704064 1049877264 11.360 100 96 186 1 1133002800 64921776 18203760
08:35:27 848 8192000000 9996912560 10378989568 1042460896 11.288 100 75 145 2 1119355376 61659024 15235456
08:36:28 6665 8192000000 9976454640 10370822144 1053103648 11.390 100 70 137 0 1115883360 49075792 13703920
08:37:39 9879 8192000000 9963921792 10338607104 1034450032 11.211 100 76 137 4 1092282880 45930736 11902112
08:38:55 9820 8192000000 10011394480 10394030080 1053659104 11.396 100 68 136 0 1136602240 69194848 13748288
08:40:14 8264 8192000000 10035928944 10424696832 1068084832 11.534 100 66 127 0 1158019584 77187296 12747456
08:41:30 7548 8192000000 10051570672 10436313088 1083023952 11.676 100 72 132 2 1176542768 79104928 14413888
08:42:43 8833 8192000000 10093999648 10486312960 1100898224 11.846 100 72 139 0 1218583424 102132928 15552272
08:44:01 10036 8192000000 10134306096 10534600704 1115874560 11.988 100 76 149 0 1252858416 121723312 15260544
08:45:15 3587 8192000000 10141962160 10555719680 1118978672 12.017 100 69 123 3 1260032384 126139440 14914272
08:46:26 3732 8192000000 10164795552 10572951552 1132211744 12.142 100 50 95 1 1283479232 140627696 10639792
08:47:41 3223 8192000000 10219442848 10614153216 1146580096 12.277 100 84 158 1 1334872352 170911456 17380800
08:48:50 921 8192000000 10259798176 10655916032 1166295920 12.462 100 74 144 1 1371759904 188732640 16731344
08:49:55 956 8192000000 10333886400 10742165504 1183060800 12.619 100 92 181 2 1446591312 241935648 21594864
08:51:00 1364 8192000000 10336739632 10749104128 1184109424 12.629 100 87 167 0 1449112288 246401296 18601568
08:52:05 1329 8192000000 10365092592 10790043648 1187222496 12.658 100 86 163 1 1476459744 270608288 18628960
08:53:11 1244 8192000000 10424608400 10829094912 1206902352 12.840 100 96 189 0 1530495840 301963888 21629600
08:54:14 745 8192000000 10428727040 10863357952 1202940128 12.804 100 95 186 0 1536407760 312256096 21211536
08:55:17 4451 8192000000 10477025600 10891952128 1212996144 12.897 100 75 150 0 1592233792 361191840 18045808
08:56:26 812 8192000000 10518095568 10930094080 1206748256 12.839 100 97 186 0 1625965440 396934736 22282448
08:57:29 2838 8192000000 10531259072 10966372352 1205425856 12.827 100 81 159 1 1636633952 411540976 19667120
08:58:36 1340 8192000000 10568540288 11000762368 1197267248 12.751 100 99 197 0 1676680528 456159520 23253760
08:59:39 988 8192000000 10573411120 10999009280 1191924048 12.701 100 79 158 0 1681887376 470774240 19189088
09:00:43 1079 8192000000 10554338992 11007352832 1175141728 12.545 98 83 154 0 1658744528 464575200 19027600
09:01:49 1202 8192000000 10584651552 11008118784 1173112656 12.526 99 93 180 0 1693813952 498407552 22293744
09:02:53 763 8192000000 10594733472 11053936640 1191167056 12.694 100 97 191 1 1697587520 483518880 22901584
09:03:56 690 8192000000 10591698560 11055087616 1178057904 12.572 100 95 184 0 1695093056 494159584 22875568
09:05:00 5127 8192000000 10555645536 11046596608 1176202880 12.555 100 75 148 0 1667095296 471813152 19079264
09:06:11 19033 8192000000 10535036512 10987098112 1170269040 12.499 100 44 86 0 1622903472 440708064 11926368
09:07:39 12295 8192000000 10470670512 10929688576 1156679456 12.372 100 33 64 0 1576082416 410716912 8686048
09:09:17 12008 8192000000 10372064304 10883903488 1147541824 12.286 100 31 62 0 1489192960 334279248 7371888
09:10:43 17748 8192000000 10193244288 10695917568 1118473168 12.013 98 22 44 0 1309830016 186053472 5303376
09:12:05 14391 8192000000 10162370224 10622689280 1141084368 12.226 100 46 86 0 1268491344 117989664 9417312
09:13:37 13861 8192000000 10180468320 10638225408 1118722144 12.015 99 34 66 0 1292699216 165872832 8104240
09:15:23 97717 8192000000 10121009632 10555678720 1105748704 11.892 97 30 59 1 1183263600 70482000 7032896
09:18:07 39827 8192000000 10106861360 10606092288 1141033008 12.225 100 25 49 0 1281868768 135350496 5485264
09:19:48 73640 8192000000 10076722640 10506539008 1132080640 12.141 98 45 73 5 1162745696 23553904 7111152
09:22:07 85006 8192000000 10083412320 10519289856 1133794192 12.157 98 12 24 0 1168096272 31422288 2879792
09:24:37 149189 8192000000 10110475200 10606252032 1094619456 11.787 95 37 74 1 1174319376 70999392 8700528
09:28:08 39346 8192000000 10071065168 10509107200 1129868720 12.120 100 40 75 0 1161858144 24142592 7846832
09:30:24 16518 8192000000 10126552208 10559369216 1149454240 12.304 100 66 128 3 1243046944 80062512 13530192
09:31:48 31124 8192000000 10117892928 10555596800 1094331136 11.784 96 41 79 0 1195942880 92347840 9263904
09:33:20 12323 8192000000 10144129248 10611634176 1125220496 12.076 100 40 74 0 1250403184 117049344 8133344
09:34:34 33381 8192000000 10078404080 10541408256 1130444128 12.126 100 13 24 0 1186343808 53360960 2538720
09:36:11 131554 8192000000 10150428288 10601926656 1095606816 11.796 95 22 44 0 1199896672 99214672 5075184
09:39:26 71842 8192000000 10158113872 10633142272 1138838320 12.205 100 22 41 0 1255903200 112368608 4696272
09:41:38 42551 8192000000 10112433824 10579095552 1100199248 11.840 97 10 20 0 1194806160 92402160 2204752
09:43:24 65739 8192000000 10091105600 10573578240 1109399664 11.927 98 20 38 0 1166438608 52657744 4381200
09:45:35 68868 8192000000 10057005072 10514038784 1064004160 11.495 94 24 47 1 1111084672 41966208 5114304
09:47:45 50384 8192000000 10116861136 10538917888 1124360288 12.068 100 29 59 0 1180154576 50138896 5655392
09:49:40 44709 8192000000 10078787760 10547159040 1093561504 11.777 97 27 54 0 1194972480 96131616 5279360
09:51:52 64061 8192000000 10095735600 10529861632 1107972400 11.913 98 30 56 0 1154861200 40878752 6010048
09:53:59 53824 8192000000 10099960000 10569035776 1116173840 11.991 99 43 86 2 1214590528 89238896 9177792
09:56:01 65740 8192000000 10052312048 10532401152 1098398832 11.822 98 20 39 1 1137854864 35003696 4452336
09:58:22 18477 8192000000 10083275904 10504007680 1135321104 12.171 100 71 129 3 1182145824 32811280 14013440
09:59:42 63529 8192000000 10084934816 10539442176 1113538240 11.966 98 13 26 0 1169289024 52975856 2774928
10:01:48 33429 8192000000 10049163712 10523029504 1119311072 12.020 99 13 26 1 1152033264 30386336 2335856
10:03:36 42512 8192000000 10063499008 10525007872 1114252256 11.973 98 12 24 0 1155079472 38262176 2565040
10:05:19 75223 8192000000 10045508064 10510217216 1104876720 11.884 98 22 44 0 1139324816 29694240 4753856
10:07:39 78750 8192000000 10100808640 10558595072 1090073136 11.743 96 14 28 0 1178970608 86051904 2845568
10:09:59 47802 8192000000 10094586704 10552070144 1116591360 11.995 100 27 44 2 1177830128 56954512 4284256
10:11:47 31850 8192000000 10109128480 10543865856 1134673920 12.165 100 44 82 1 1205151472 62041472 8436080
10:13:22 25871 8192000000 10148061776 10599874560 1094555632 11.786 96 42 84 1 1214495296 110977024 8962640
10:14:48 29965 8192000000 10124682192 10593198080 1113733168 11.968 99 49 95 0 1220272800 96974080 9565552
10:16:25 29896 8192000000 10067572080 10499235840 1115055552 11.980 99 29 58 1 1176536192 55572352 5908288
10:17:57 14366 8192000000 10108580784 10536214528 1132758112 12.147 100 53 102 2 1202132720 59338640 10035968
10:19:12 116438 8192000000 10103147968 10562908160 1137698352 12.194 100 33 64 0 1232606384 88122928 6785104
10:22:10 33966 8192000000 10066203648 10527543296 1126966240 12.093 99 9 18 0 1159372112 30253184 2152688
10:23:51 60068 8192000000 10083330368 10551369728 1116860288 11.997 99 18 36 0 1173594224 52736336 3997600
10:25:52 47479 8192000000 10066319584 10506518528 1083280480 11.679 96 15 26 0 1124077584 38007040 2790064
10:27:43 76496 8192000000 10065968096 10523336704 1070208224 11.554 95 20 40 0 1112652704 38273856 4170624
10:29:59 42570 8192000000 10118258016 10551173120 1093686736 11.778 97 37 74 1 1158041776 56686896 7668144
10:31:47 32702 8192000000 10175561104 10626830336 1110801632 11.940 98 39 78 0 1261200768 142059040 8340096
10:33:35 48446 8192000000 10054618544 10479538176 1095127040 11.791 97 55 99 1 1127198768 20788240 11283488
10:35:27 39915 8192000000 10117796192 10555432960 1121729616 12.043 100 31 59 0 1188312480 60872128 5710736
10:37:08 49877 8192000000 9631474640 10049474560 732137312 8.204 65 6 12 0 784195056 50911504 1146240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-02-2023 10:40:16 to 27-02-2023 10:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-02-2023 10:40:33 to 27-02-2023 10:40:35.

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          223623
MON$OLDEST_ACTIVE               224448
MON$OLDEST_SNAPSHOT             224448
MON$NEXT_TRANSACTION            224448
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                       2059360
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:327: 
:328: mscs-test-161.budzdorov.ru    Mon Feb 27 10:38:12 2023
:329:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:330: 
:331: 
:332: mscs-test-161.budzdorov.ru    Mon Feb 27 10:38:41 2023
:333:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:334: 
--- end of diff output ---

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

27.02.23 10:40:37. 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-02-27 10:40:39 No severe PSQL-related problems occured
27.02.23 10:40:41. End of report.