Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.03.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 15242644 kB
MemAvailable 30450404 kB
Buffers 0 kB
Cached 16488408 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-04 10:37:27
DTS_END                         2023-03-04 10:37:27.2360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45314
TRN_ID                          223116
ATT_ID                          663
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: 8908.39 23701 1069007
customer order: creation 171.38 470 20566
customer order: refuse 14.52 379 1742
order to supplier: creation 45.46 1379 5455
order to supplier: removal 3.43 152690 412
invoice (draft): creation 45.50 3066 5460
invoice (draft): removal 48.58 1037 5829
invoice accept: apply 42.63 26486 5115
invoice accept: cancel 44.20 1181 5304
customer reserve: creation 3929.94 257 471593
customer reserve: removal 4268.29 96 512195
realization accept: apply 83.37 285 10004
realization accept: cancel 80.48 161 9658
payment from customer: creation 60.13 181 7216
payment from customer: removal 5.79 113 695
payment to supplier: creation 48.26 203 5791
payment to supplier: removal 3.53 116 423
service: total inventory turnovers 4.17 3624 500
service: total monetary turnovers 7.81 347 937
service: aggregate perf. data 0.88 16130 106
service: refresh index statistics 0.05 265811 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-03-2023 10:40:21 to 04-03-2023 10:40:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3689
TEST_TIME 2 4885
TEST_TIME 3 4883
TEST_TIME 4 4672
TEST_TIME 5 4788
TEST_TIME 6 4851
TEST_TIME 7 5033
TEST_TIME 8 5257
TEST_TIME 9 5241
TEST_TIME 10 5271
TEST_TIME 11 5668
TEST_TIME 12 5899
TEST_TIME 13 5961
TEST_TIME 14 6115
TEST_TIME 15 6359
TEST_TIME 16 6641
TEST_TIME 17 6789
TEST_TIME 18 7304
TEST_TIME 19 7357
TEST_TIME 20 7636
TEST_TIME 21 7561
TEST_TIME 22 7737
TEST_TIME 23 8046
TEST_TIME 24 8471
TEST_TIME 25 8417
TEST_TIME 26 8889
TEST_TIME 27 9130
TEST_TIME 28 9483
TEST_TIME 29 10236
TEST_TIME 30 10711
TEST_TIME 31 11019
TEST_TIME 32 10897
TEST_TIME 33 10578
TEST_TIME 34 10332
TEST_TIME 35 10397
TEST_TIME 36 10244
TEST_TIME 37 10213
TEST_TIME 38 10165
TEST_TIME 39 10039
TEST_TIME 40 9969
TEST_TIME 41 9895
TEST_TIME 42 9869
TEST_TIME 43 9727
TEST_TIME 44 9719
TEST_TIME 45 9557
TEST_TIME 46 9433
TEST_TIME 47 9338
TEST_TIME 48 9380
TEST_TIME 49 9235
TEST_TIME 50 9233
TEST_TIME 51 9167
TEST_TIME 52 9094
TEST_TIME 53 9085
TEST_TIME 54 9073
TEST_TIME 55 9100
TEST_TIME 56 9189
TEST_TIME 57 9240
TEST_TIME 58 9243
TEST_TIME 59 9244
TEST_TIME 60 9284
TEST_TIME 61 9268
TEST_TIME 62 9234
TEST_TIME 63 9169
TEST_TIME 64 9131
TEST_TIME 65 9084
TEST_TIME 66 9059
TEST_TIME 67 8982
TEST_TIME 68 8930
TEST_TIME 69 8933
TEST_TIME 70 8951
TEST_TIME 71 8939
TEST_TIME 72 8931
TEST_TIME 73 8926
TEST_TIME 74 8898
TEST_TIME 75 9030
TEST_TIME 76 9097
TEST_TIME 77 9120
TEST_TIME 78 9216
TEST_TIME 79 9254
TEST_TIME 80 9276
TEST_TIME 81 9210
TEST_TIME 82 9271
TEST_TIME 83 9263
TEST_TIME 84 9234
TEST_TIME 85 9191
TEST_TIME 86 9164
TEST_TIME 87 9182
TEST_TIME 88 9193
TEST_TIME 89 9160
TEST_TIME 90 9171
TEST_TIME 91 9185
TEST_TIME 92 9178
TEST_TIME 93 9190
TEST_TIME 94 9222
TEST_TIME 95 9276
TEST_TIME 96 9236
TEST_TIME 97 9164
TEST_TIME 98 9096
TEST_TIME 99 9074
TEST_TIME 100 9079
TEST_TIME 101 9069
TEST_TIME 102 9014
TEST_TIME 103 8975
TEST_TIME 104 8936
TEST_TIME 105 8931
TEST_TIME 106 9003
TEST_TIME 107 8976
TEST_TIME 108 8977
TEST_TIME 109 8987
TEST_TIME 110 8977
TEST_TIME 111 8983
TEST_TIME 112 9004
TEST_TIME 113 9038
TEST_TIME 114 9018
TEST_TIME 115 9040
TEST_TIME 116 9032
TEST_TIME 117 9024
TEST_TIME 118 8978
TEST_TIME 119 8977
TEST_TIME 120 8924

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-03-2023 10:40:25 to 04-03-2023 10:40:29.

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 1502810 1502810 0 0.00 0 31996 14 0 0
doc_list_biud 1502799 1502799 0 0.00 0 49887 39 0 0
sp_add_invoice_to_stock 5234 5115 119 2.27 45 293458 26487 0 119
sp_cancel_adding_invoice 5304 5304 0 0.00 7 74010 1182 0 0
sp_cancel_client_order 1742 1742 0 0.00 5 10954 380 0 0
sp_cancel_customer_reserve 512195 512195 0 0.00 1 30936 97 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 4160 114 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 5306 116 0 0
sp_cancel_supplier_invoice 5840 5829 11 0.18 3 49906 1037 0 11
sp_cancel_supplier_order 412 412 0 0.00 8 1458675 152691 0 0
sp_cancel_write_off 9658 9658 0 0.00 2 10299 162 0 0
sp_client_order 20566 20566 0 0.00 5 37425 471 0 0
sp_customer_reserve 471593 471593 0 0.00 0 126390 258 0 0
sp_fill_shopping_cart 31471 31471 0 0.00 1 31710 80 0 0
sp_fill_shopping_cart_clo_res 471553 471553 0 0.00 0 13319 19 0 0
sp_get_clo_for_invoice 5069 5069 0 0.00 0 24341 310 0 0
sp_kill_cost_storno 16080 16080 0 0.00 0 17189 68 0 0
sp_kill_qstorno_ret_qs2qd 1040289 1040289 0 0.00 0 49884 43 0 0
sp_kill_qty_storno 550211 550211 0 0.00 0 49886 98 0 0
sp_lock_selected_doc 20888 20888 0 0.00 0 5835 7 0 0
sp_make_cost_storno 28079 28079 0 0.00 0 14722 77 0 0
sp_multiply_rows_for_pdistr 28078 28078 0 0.00 0 10689 35 0 0
sp_multiply_rows_for_qdistr 20565 20565 0 0.00 0 14391 172 0 0
sp_pay_from_customer 7216 7216 0 0.00 5 20556 182 0 0
sp_pay_to_supplier 5791 5791 0 0.00 6 24790 203 0 0
sp_payment_common 13006 13006 0 0.00 2 6521 43 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 10898 301 0 0
sp_qd_handle_on_invoice_upd_sts 10371 10371 0 0.00 0 32329 493 0 0
sp_qd_handle_on_reserve_upd_sts 10004 10004 0 0.00 0 18158 63 0 0
sp_reserve_write_off 10004 10004 0 0.00 9 25259 285 0 0
sp_supplier_invoice 5460 5460 0 0.00 9 85923 3067 0 0
sp_supplier_order 5455 5455 0 0.00 7 52405 1380 0 0
srv_aggregate_perf_data 106 106 0 0.00 2683 120962 16131 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 802 151824 37404 0 0
srv_find_qd_qs_mism 1019457 1019457 0 0.00 0 31992 12 0 0
srv_make_invnt_saldo 500 500 0 0.00 88 75140 3624 0 0
srv_make_money_saldo 937 937 0 0.00 1 11709 347 0 0
srv_recalc_idx_stat 6 6 0 0.00 88432 477741 265812 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5120 5120 0 0.00 0 37150 789 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 1 12621 433 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 7610 32 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 855 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 4046 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 854 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 796 20 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 90026 5523 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 1288 17 0 0
t$perf-norm:sp_client_order 20566 20566 0 0.00 0 19891 35 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 4547 16 0 0
t$perf-norm:sp_pay_from_customer 7216 7216 0 0.00 0 19714 21 0 0
t$perf-norm:sp_pay_to_supplier 5791 5791 0 0.00 0 4594 19 0 0
t$perf-norm:sp_reserve_write_off 10004 10004 0 0.00 0 19893 25 0 0
t$perf-norm:sp_supplier_invoice 5461 5461 0 0.00 0 4814 24 0 0
t$perf-norm:sp_supplier_order 5455 5455 0 0.00 0 8939 30 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 419 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 173 6 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 1488 14 0 0
t$perf-norm:srv_make_money_saldo 937 937 0 0.00 0 1808 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 411 120 0 0
v_add_invoice_to_stock 10368 10130 238 2.29 0 24660 28 0 238
v_all_customers 37768 37768 0 0.00 0 74 3 0 0
v_all_suppliers 30264 30264 0 0.00 0 197 4 0 0
v_all_wares 41128 41128 0 0.00 0 76 4 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 5967 20 0 0
v_cancel_client_order 3484 3484 0 0.00 0 4767 17 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1743 15 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 4790 16 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1468 13 0 0
v_cancel_supplier_invoice 840 818 22 2.61 0 1291 23 0 22
v_cancel_supplier_order 824 824 0 0.00 0 3395 13 0 0
v_cancel_write_off 2062 2062 0 0.00 0 9275 31 0 0
v_max_id_clo_ord 5453 5453 0 0.00 0 986 5 0 0
v_max_id_clo_res 989 989 0 0.00 0 2384 19 0 0
v_max_id_ord_sup 5453 5453 0 0.00 0 8650 7 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 82 7 0 0
v_max_non_paid_realizn 3738 3738 0 0.00 0 2052 8 0 0
v_min_id_clo_ord 5453 5453 0 0.00 0 3174 8 0 0
v_min_id_clo_res 989 989 0 0.00 0 1199 8 0 0
v_min_id_ord_sup 5453 5453 0 0.00 0 8625 13 0 0
v_min_non_paid_invoice 5791 5791 0 0.00 0 5078 15 0 0
v_min_non_paid_realizn 7215 7215 0 0.00 0 6515 20 0 0
v_our_firm 10314 10314 0 0.00 0 75 4 0 0
v_reserve_write_off 20004 20004 0 0.00 0 24668 16 0 0
x_lock_depdocs_on_canc_invoice 10724 10724 0 0.00 0 36937 129 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 15313 348 0 0
x_make_qstorno_1000_1200 5455 5455 0 0.00 2 52231 1222 0 0
x_make_qstorno_1200_2000 5460 5460 0 0.00 4 85907 2845 0 0
x_make_qstorno_2100_3300 471589 471589 0 0.00 0 126377 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-03-2023 10:40:30 to 04-03-2023 10:40:34.

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:31 931 8192000000 8927576864 8984543232 14069152 0.171 4 2 4 0 14479360 101296 308912
08:08:33 24302 8192000000 9723181136 10019962880 799067072 8.887 100 87 174 0 827936880 7793312 21076496
08:10:00 24645 8192000000 9757764704 10065887232 832930512 9.229 100 91 182 0 867671888 13025616 21715760
08:11:34 13992 8192000000 9802550048 10121797632 863530256 9.535 100 80 160 0 905213280 22967088 18715936
08:12:56 9335 8192000000 9868224976 10200109056 913942384 10.036 100 86 164 0 973212544 41625552 17644608
08:14:08 3740 8192000000 9928695360 10272858112 967025104 10.558 100 88 173 0 1042518432 57125408 18367920
08:15:16 1866 8192000000 9960257920 10315104256 1002238704 10.900 100 87 174 2 1079885040 59933728 17712608
08:16:20 1655 8192000000 9997216656 10359341056 1023848992 11.109 100 91 179 1 1110537072 69291056 17397024
08:17:24 1060 8192000000 10000160352 10366726144 1040808704 11.272 100 87 170 0 1119212224 61795152 16608368
08:18:26 810 8192000000 10042952352 10418966528 1056981360 11.428 100 93 183 1 1161670896 85918048 18771488
08:19:28 910 8192000000 10028779536 10409431040 1074255728 11.593 100 94 179 2 1152739216 59937152 18546336
08:20:30 1058 8192000000 10068077152 10449502208 1075647072 11.606 100 95 186 3 1186500352 92668320 18184960
08:21:32 11238 8192000000 10026692752 10426900480 1079002480 11.638 100 52 97 1 1156492240 66922688 10567072
08:22:45 1207 8192000000 10052082016 10441379840 1083703840 11.683 100 94 184 1 1172208288 69319024 19185424
08:23:48 1153 8192000000 10037005888 10424193024 1089306704 11.736 100 85 154 3 1156784080 50670496 16806880
08:24:50 1571 8192000000 10045487824 10439032832 1093379712 11.775 100 79 149 1 1167681392 58862000 15439680
08:25:52 1049 8192000000 10067495120 10459553792 1098767552 11.826 100 98 185 1 1183693376 66334608 18591216
08:26:55 1123 8192000000 10062805424 10454401024 1094292448 11.783 100 97 190 3 1185493968 72283664 18917856
08:27:57 1083 8192000000 10064866096 10454335488 1094781200 11.788 100 93 178 1 1188967968 75486224 18700544
08:28:58 1166 8192000000 10055776064 10445443072 1092020080 11.762 100 89 170 1 1177359600 68814656 16524864
08:30:00 1033 8192000000 10030484128 10417459200 1077532896 11.624 100 93 183 0 1155834192 60352608 17948688
08:31:03 1295 8192000000 10032198736 10415812608 1075348016 11.603 100 80 155 0 1160017552 68631600 16037936
08:32:05 1876 8192000000 10023731536 10410983424 1062991696 11.485 100 74 145 1 1146010128 68300480 14717952
08:33:08 1233 8192000000 9998746016 10382053376 1041029632 11.275 100 79 154 1 1120374144 64472784 14871728
08:34:10 1067 8192000000 9989312544 10362802176 1032538848 11.193 100 93 172 1 1112474112 60816272 19118992
08:35:11 733 8192000000 9992380944 10367430656 1040363952 11.268 100 84 164 1 1112948816 55590944 16993920
08:36:14 9635 8192000000 9956333536 10346352640 1023356880 11.104 99 49 97 0 1079466800 45856128 10253792
08:37:24 1069 8192000000 9964733472 10336071680 1045384384 11.316 100 95 184 4 1086349520 21937456 19027680
08:38:31 6506 8192000000 10023306384 10404298752 1069221312 11.545 100 75 140 1 1141105264 57612288 14271664
08:39:44 6902 8192000000 10055106608 10441465856 1085449776 11.699 100 73 135 3 1175266592 76116304 13700512
08:40:54 1766 8192000000 10063189344 10454618112 1096960080 11.809 100 82 150 2 1186319136 73967264 15391792
08:41:59 2361 8192000000 10125592960 10512060416 1114260960 11.973 100 73 138 0 1240339152 111341376 14736816
08:43:06 5211 8192000000 10143664336 10541477888 1118904064 12.017 100 72 134 1 1268615824 135365072 14346688
08:44:20 3019 8192000000 10202811872 10598850560 1132345376 12.143 100 76 139 1 1316435600 169563360 14526864
08:45:29 4500 8192000000 10222482816 10619514880 1147655232 12.287 100 76 145 2 1333949424 170719104 15575088
08:46:39 2220 8192000000 10261650496 10662371328 1155022176 12.357 100 79 153 0 1378667712 206523888 17121648
08:47:45 1220 8192000000 10313424656 10709606400 1160734480 12.410 100 88 164 1 1422010512 243014848 18261184
08:48:52 927 8192000000 10333250736 10743902208 1174790864 12.542 100 92 184 0 1445842128 249618032 21433232
08:49:56 1183 8192000000 10379286528 10780762112 1178991520 12.581 100 95 183 2 1487670192 286676352 22002320
08:51:00 1717 8192000000 10406342384 10825502720 1187658640 12.662 100 97 191 0 1517822960 308033856 22130464
08:52:06 792 8192000000 10407340192 10826346496 1192976768 12.711 100 96 187 0 1515265680 299815840 22473072
08:53:09 1150 8192000000 10442135024 10869645312 1190561744 12.689 100 84 162 0 1553974848 343524560 19888544
08:54:13 823 8192000000 10426525376 10861416448 1173784608 12.532 99 87 167 2 1535902160 341969344 20148208
08:55:17 1066 8192000000 10462857488 10882871296 1185828000 12.645 100 88 170 0 1569938640 363394384 20716256
08:56:20 841 8192000000 10442848640 10885832704 1174619600 12.540 100 97 188 3 1548447376 352218944 21608832
08:57:23 835 8192000000 10487237456 10889920512 1176058096 12.553 100 95 187 2 1590957056 392840352 22058608
08:58:27 1086 8192000000 10520287120 10928783360 1172852272 12.523 100 97 188 1 1624075136 428560928 22661936
08:59:31 6693 8192000000 10525529472 11001937920 1180267088 12.593 100 67 128 0 1646752560 449520688 16964784
09:00:46 8390 8192000000 10511878080 11008876544 1183835936 12.626 100 60 114 1 1628974144 430461456 14676752
09:02:00 10339 8192000000 10497769680 10934919168 1178379120 12.575 100 40 79 0 1604410032 415645136 10385776
09:03:30 12961 8192000000 10503739136 10990182400 1145521648 12.267 99 40 79 0 1589676976 433310992 10844336
09:04:52 14904 8192000000 10466498896 10959491072 1140403504 12.219 100 29 58 0 1574106384 425602560 8100320
09:06:27 71041 8192000000 10184366608 10702319616 1140961008 12.225 100 18 36 0 1362075136 216847904 4266224
09:08:39 64698 8192000000 10072459920 10505924608 1096716400 11.806 97 18 36 0 1176033888 75273184 4044304
09:10:47 79880 8192000000 10122891168 10560933888 1136490368 12.183 100 56 103 1 1213962256 65528368 11943520
09:13:15 128065 8192000000 10084151648 10554265600 1144561184 12.258 100 28 50 2 1206915424 57315728 5038512
09:16:31 151807 8192000000 10082527344 10527543296 1085403632 11.699 95 20 36 0 1141662544 52729360 3529552
09:20:04 47943 8192000000 10070257568 10518712320 1116091824 11.990 98 11 20 0 1158643024 40660432 1890768
09:23:14 50574 8192000000 10071254096 10546278400 1131291568 12.134 99 8 16 0 1177948176 45062192 1594416
09:25:06 66041 8192000000 10074063184 10550439936 1147311792 12.284 100 18 36 0 1192304256 41690288 3302176
09:27:43 54066 8192000000 10069740640 10562846720 1147893936 12.290 100 9 18 0 1190482768 41134000 1454832
09:29:37 63333 8192000000 10157984096 10601226240 1137562160 12.193 98 42 78 1 1233888480 86495072 9831248
09:31:41 47201 8192000000 10120734304 10590756864 1135442336 12.173 99 27 54 0 1227922736 86838656 5641744
09:33:42 28814 8192000000 10114875056 10575364096 1144556544 12.258 100 42 83 1 1213708704 60380464 8771696
09:35:22 55158 8192000000 10116641648 10561630208 1105846400 11.893 97 37 74 0 1216656000 102710848 8098752
09:37:19 26177 8192000000 10088070656 10543403008 1112996096 11.961 98 35 70 0 1176942064 56936208 7009760
09:38:47 10340 8192000000 10082438464 10504749056 1128551632 12.108 100 53 106 2 1176452512 37783280 10117600
09:39:57 108081 8192000000 10083001984 10556792832 1122479296 12.050 99 44 88 0 1185671280 54229360 8962624
09:42:46 48537 8192000000 10069009088 10530336768 1136060752 12.178 100 10 20 0 1175290912 37259760 1970400
09:44:34 58161 8192000000 10104862816 10551566336 1108752656 11.921 97 13 22 0 1163715856 52747024 2216176
09:46:37 58838 8192000000 10112927344 10585706496 1094362512 11.784 95 29 58 0 1183734048 82856400 6515136
09:48:41 27160 8192000000 10144688736 10587025408 1120891296 12.035 98 36 70 0 1223450448 94566368 7992784
09:50:09 40158 8192000000 10178647344 10624561152 1131019728 12.131 98 37 74 0 1262482304 123281648 8180928
09:51:56 33414 8192000000 10201054336 10715541504 1138554512 12.202 99 23 46 0 1306954704 162850672 5549520
09:53:30 49120 8192000000 10132086752 10577588224 1128658624 12.109 99 16 30 0 1234921056 102682848 3579584
09:55:22 24464 8192000000 10129128592 10553966592 1107700464 11.911 97 45 92 0 1191333456 73989120 9643872
09:56:50 69626 8192000000 10146627184 10600194048 1099121616 11.829 97 20 38 0 1194194176 90889168 4183392
09:59:00 59434 8192000000 10076747504 10575409152 1120190144 12.029 100 18 37 0 1230332544 105401200 4741200
10:01:06 63041 8192000000 10086978880 10524139520 1113098304 11.962 98 18 35 1 1160989984 43838912 4052768
10:03:13 29295 8192000000 10127016032 10559934464 1139171216 12.208 99 50 100 0 1204414112 54476384 10766512
10:04:44 62497 8192000000 10072766032 10521735168 1100586768 11.843 96 22 42 0 1154150576 48776704 4787104
10:06:47 77205 8192000000 10084222144 10514821120 1117531664 12.004 98 27 50 1 1161547760 38674336 5341760
10:09:08 50213 8192000000 10121927472 10561916928 1137156480 12.189 99 19 38 0 1198630304 57532480 3941344
10:11:02 45865 8192000000 10120466064 10565890048 1147223616 12.283 100 42 84 0 1217302160 61940896 8137648
10:12:49 37649 8192000000 10085856032 10553925632 1103704032 11.873 96 37 74 0 1165050304 54141728 7204544
10:14:27 98247 8192000000 10081249984 10518999040 1098479376 11.823 96 34 68 1 1141655536 36196400 6979760
10:17:06 33154 8192000000 10081280880 10546028544 1125505760 12.079 99 32 64 0 1187916128 55986256 6424112
10:18:41 39559 8192000000 10087578496 10561392640 1138568320 12.202 100 10 19 0 1188590688 48199568 1822800
10:20:21 52339 8192000000 10121664512 10614648832 1135736896 12.175 99 12 24 0 1211196288 72912080 2547312
10:22:13 36006 8192000000 10114092656 10563510272 1091293360 11.755 96 26 52 0 1207105744 110187968 5624416
10:23:58 23807 8192000000 10062180816 10488352768 1113288048 11.964 99 36 72 0 1141158832 19921696 7949088
10:25:24 46729 8192000000 10090267376 10516635648 1094827200 11.789 98 36 69 0 1165427216 63496976 7103040
10:27:23 49886 8192000000 10137295552 10568503296 1126584160 12.089 100 32 64 0 1210824384 77428848 6811376
10:29:13 77632 8192000000 10088934144 10551418880 1107624672 11.910 98 23 46 1 1213956672 101638368 4693632
10:31:36 30716 8192000000 10098875120 10534617088 1084451520 11.690 97 35 68 0 1157450960 66207440 6792000
10:33:17 15139 8192000000 10075501360 10509295616 1108977184 11.923 100 36 71 0 1166735392 50415728 7342480
10:34:34 69111 8192000000 10114324800 10549145600 1122658512 12.052 100 41 81 0 1193136768 61857296 8620960
10:36:48 60426 8192000000 9388416816 9742942208 515204368 5.916 46 3 6 0 544421232 28719264 497600

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-03-2023 10:40:36 to 04-03-2023 10:40:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-03-2023 10:40:53 to 04-03-2023 10:40:55.

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          223115
MON$OLDEST_ACTIVE               223364
MON$OLDEST_SNAPSHOT             223364
MON$NEXT_TRANSACTION            223364
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                       2039520
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2905 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2905 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2905 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2905 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Sat Mar  4 10:38:33 2023
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
--- end of diff output ---

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

04.03.23 10:40:56. 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-04 10:40:59 No severe PSQL-related problems occured
04.03.23 10:41:00. End of report.