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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16335004 kB
MemAvailable 31570504 kB
Buffers 0 kB
Cached 15341900 kB
SwapCached 2204 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.2
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-04-01 13:37:11
DTS_END                         2022-04-01 13:37:11.5350
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/49316
TRN_ID                          218786
ATT_ID                          626
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: 8828.30 25022 1059396
customer order: creation 172.10 485 20652
customer order: refuse 14.38 447 1725
order to supplier: creation 45.43 1553 5451
order to supplier: removal 3.51 172278 421
invoice (draft): creation 46.05 3398 5526
invoice (draft): removal 47.95 1146 5754
invoice accept: apply 41.51 29265 4981
invoice accept: cancel 42.80 1367 5136
customer reserve: creation 3939.25 274 472710
customer reserve: removal 4178.79 113 501455
realization accept: apply 82.22 306 9866
realization accept: cancel 83.72 189 10046
payment from customer: creation 59.12 167 7094
payment from customer: removal 6.08 130 730
payment to supplier: creation 49.53 212 5943
payment to supplier: removal 3.45 131 414
service: total inventory turnovers 3.91 4165 469
service: total monetary turnovers 7.63 368 915
service: aggregate perf. data 0.87 18802 104
service: refresh index statistics 0.03 265644 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-04-2022 13:38:27 to 01-04-2022 13:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7421
TEST_TIME 2 6892
TEST_TIME 3 6838
TEST_TIME 4 6522
TEST_TIME 5 6364
TEST_TIME 6 6279
TEST_TIME 7 6369
TEST_TIME 8 5933
TEST_TIME 9 5998
TEST_TIME 10 5896
TEST_TIME 11 5899
TEST_TIME 12 6119
TEST_TIME 13 6179
TEST_TIME 14 6245
TEST_TIME 15 6181
TEST_TIME 16 6266
TEST_TIME 17 6661
TEST_TIME 18 6673
TEST_TIME 19 6921
TEST_TIME 20 7093
TEST_TIME 21 7116
TEST_TIME 22 7027
TEST_TIME 23 7145
TEST_TIME 24 7604
TEST_TIME 25 7723
TEST_TIME 26 7918
TEST_TIME 27 7966
TEST_TIME 28 8314
TEST_TIME 29 8486
TEST_TIME 30 8741
TEST_TIME 31 8998
TEST_TIME 32 9421
TEST_TIME 33 9368
TEST_TIME 34 9491
TEST_TIME 35 9847
TEST_TIME 36 9989
TEST_TIME 37 10025
TEST_TIME 38 10292
TEST_TIME 39 10144
TEST_TIME 40 10310
TEST_TIME 41 10561
TEST_TIME 42 10462
TEST_TIME 43 10307
TEST_TIME 44 10240
TEST_TIME 45 10124
TEST_TIME 46 10116
TEST_TIME 47 10197
TEST_TIME 48 10061
TEST_TIME 49 9918
TEST_TIME 50 9773
TEST_TIME 51 9765
TEST_TIME 52 9704
TEST_TIME 53 9557
TEST_TIME 54 9543
TEST_TIME 55 9482
TEST_TIME 56 9506
TEST_TIME 57 9548
TEST_TIME 58 9540
TEST_TIME 59 9492
TEST_TIME 60 9470
TEST_TIME 61 9497
TEST_TIME 62 9454
TEST_TIME 63 9385
TEST_TIME 64 9359
TEST_TIME 65 9337
TEST_TIME 66 9322
TEST_TIME 67 9285
TEST_TIME 68 9237
TEST_TIME 69 9251
TEST_TIME 70 9265
TEST_TIME 71 9327
TEST_TIME 72 9281
TEST_TIME 73 9262
TEST_TIME 74 9231
TEST_TIME 75 9305
TEST_TIME 76 9381
TEST_TIME 77 9348
TEST_TIME 78 9330
TEST_TIME 79 9394
TEST_TIME 80 9298
TEST_TIME 81 9272
TEST_TIME 82 9207
TEST_TIME 83 9157
TEST_TIME 84 9168
TEST_TIME 85 9122
TEST_TIME 86 9064
TEST_TIME 87 9050
TEST_TIME 88 8985
TEST_TIME 89 8970
TEST_TIME 90 9103
TEST_TIME 91 9058
TEST_TIME 92 9047
TEST_TIME 93 9013
TEST_TIME 94 9009
TEST_TIME 95 9150
TEST_TIME 96 9180
TEST_TIME 97 9159
TEST_TIME 98 9154
TEST_TIME 99 9189
TEST_TIME 100 9185
TEST_TIME 101 9191
TEST_TIME 102 9182
TEST_TIME 103 9206
TEST_TIME 104 9146
TEST_TIME 105 9109
TEST_TIME 106 9084
TEST_TIME 107 9100
TEST_TIME 108 9049
TEST_TIME 109 9021
TEST_TIME 110 8987
TEST_TIME 111 8947
TEST_TIME 112 8911
TEST_TIME 113 8913
TEST_TIME 114 8890
TEST_TIME 115 8881
TEST_TIME 116 8913
TEST_TIME 117 8933
TEST_TIME 118 8922
TEST_TIME 119 8906
TEST_TIME 120 8853

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-04-2022 13:38:31 to 01-04-2022 13:38:34.

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 1493446 1493446 0 0.00 0 19526 15 0 0
doc_list_biud 1493446 1493446 0 0.00 0 34201 44 0 0
sp_add_invoice_to_stock 5093 4981 112 2.19 44 200936 29265 0 112
sp_cancel_adding_invoice 5136 5136 0 0.00 6 78566 1368 0 0
sp_cancel_client_order 1725 1725 0 0.00 7 10927 447 0 0
sp_cancel_customer_reserve 501455 501455 0 0.00 1 34227 113 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 3 5080 131 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 4631 132 0 0
sp_cancel_supplier_invoice 5762 5754 8 0.13 2 20559 1146 0 8
sp_cancel_supplier_order 421 421 0 0.00 9 1484803 172279 0 0
sp_cancel_write_off 10046 10046 0 0.00 2 11212 189 0 0
sp_client_order 20652 20652 0 0.00 5 15167 485 0 0
sp_customer_reserve 472710 472710 0 0.00 1 54497 275 0 0
sp_fill_shopping_cart 31623 31623 0 0.00 1 10479 82 0 0
sp_fill_shopping_cart_clo_res 472679 472679 0 0.00 0 15079 19 0 0
sp_get_clo_for_invoice 4942 4942 0 0.00 0 28143 281 0 0
sp_kill_cost_storno 16326 16326 0 0.00 0 9776 89 0 0
sp_kill_qstorno_ret_qs2qd 1019131 1019131 0 0.00 0 34194 50 0 0
sp_kill_qty_storno 539345 539345 0 0.00 0 34200 114 0 0
sp_lock_selected_doc 20682 20682 0 0.00 0 7990 9 0 0
sp_make_cost_storno 27845 27845 0 0.00 0 11459 86 0 0
sp_multiply_rows_for_pdistr 27845 27845 0 0.00 0 7825 42 0 0
sp_multiply_rows_for_qdistr 20652 20652 0 0.00 0 9966 181 0 0
sp_pay_from_customer 7094 7094 0 0.00 6 11995 167 0 0
sp_pay_to_supplier 5943 5943 0 0.00 6 11883 213 0 0
sp_payment_common 13037 13037 0 0.00 2 9833 45 0 0
sp_qd_handle_on_cancel_clo 1725 1725 0 0.00 1 10918 368 0 0
sp_qd_handle_on_invoice_upd_sts 10078 10078 0 0.00 0 19200 567 0 0
sp_qd_handle_on_reserve_upd_sts 9866 9866 0 0.00 0 12427 70 0 0
sp_reserve_write_off 9866 9866 0 0.00 9 16383 307 0 0
sp_supplier_invoice 5526 5526 0 0.00 10 65575 3399 0 0
sp_supplier_order 5451 5451 0 0.00 9 27180 1554 0 0
srv_aggregate_perf_data 104 104 0 0.00 2574 286432 18803 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 838 143977 27592 0 0
srv_find_qd_qs_mism 1009316 1009316 0 0.00 0 19525 13 0 0
srv_make_invnt_saldo 469 469 0 0.00 69 99162 4165 0 0
srv_make_money_saldo 915 915 0 0.00 1 10628 369 0 0
srv_recalc_idx_stat 4 4 0 0.00 99470 444702 265645 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4984 4984 0 0.00 0 9724 927 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 8524 491 0 0
t$perf-norm:sp_cancel_client_order 1725 1725 0 0.00 0 4099 33 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 2961 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 3405 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 593 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 1214 34 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 101073 7606 0 0
t$perf-norm:sp_cancel_write_off 1028 1028 0 0.00 0 2824 19 0 0
t$perf-norm:sp_client_order 20652 20652 0 0.00 0 10042 38 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 963 15 0 0
t$perf-norm:sp_pay_from_customer 7094 7094 0 0.00 0 5289 20 0 0
t$perf-norm:sp_pay_to_supplier 5943 5943 0 0.00 0 8692 23 0 0
t$perf-norm:sp_reserve_write_off 9866 9866 0 0.00 0 13373 25 0 0
t$perf-norm:sp_supplier_invoice 5526 5526 0 0.00 0 5495 34 0 0
t$perf-norm:sp_supplier_order 5451 5451 0 0.00 0 10442 35 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 777 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 1074 42 0 0
t$perf-norm:srv_make_invnt_saldo 469 469 0 0.00 0 1237 20 0 0
t$perf-norm:srv_make_money_saldo 915 915 0 0.00 0 1465 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 184 55 0 0
v_add_invoice_to_stock 10104 9880 224 2.21 0 6458 27 0 224
v_all_customers 38220 38220 0 0.00 0 85 4 0 0
v_all_suppliers 30952 30952 0 0.00 0 103 4 0 0
v_all_wares 41302 41302 0 0.00 0 119 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 3934 20 0 0
v_cancel_client_order 3450 3450 0 0.00 0 1997 12 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 2342 14 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 867 10 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 500 12 0 0
v_cancel_supplier_invoice 868 852 16 1.84 0 5273 31 0 16
v_cancel_supplier_order 842 842 0 0.00 0 642 12 0 0
v_cancel_write_off 2056 2056 0 0.00 0 6647 25 0 0
v_max_id_clo_ord 5446 5446 0 0.00 0 855 5 0 0
v_max_id_clo_res 997 997 0 0.00 0 3571 20 0 0
v_max_id_ord_sup 5524 5524 0 0.00 0 7023 8 0 0
v_max_non_paid_invoice 1436 1436 0 0.00 0 1372 8 0 0
v_max_non_paid_realizn 3492 3492 0 0.00 0 5426 9 0 0
v_min_id_clo_ord 5446 5446 0 0.00 0 4101 10 0 0
v_min_id_clo_res 997 997 0 0.00 0 1852 9 0 0
v_min_id_ord_sup 5524 5524 0 0.00 0 2598 7 0 0
v_min_non_paid_invoice 5943 5943 0 0.00 0 9825 19 0 0
v_min_non_paid_realizn 7094 7094 0 0.00 0 8641 12 0 0
v_our_firm 10286 10286 0 0.00 0 55 4 0 0
v_reserve_write_off 19728 19728 0 0.00 0 6934 14 0 0
x_lock_depdocs_on_canc_invoice 10464 10464 0 0.00 0 19293 131 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 12536 378 0 0
x_make_qstorno_1000_1200 5451 5451 0 0.00 2 27119 1381 0 0
x_make_qstorno_1200_2000 5526 5526 0 0.00 5 65498 3181 0 0
x_make_qstorno_2100_3300 472710 472710 0 0.00 0 54494 243 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-04-2022 13:38:35 to 01-04-2022 13:38:39.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:15 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:16 37116 8192000000 9733623248 10037977088 803333136 8.930 100 87 174 0 832222064 7629424 21259504
11:09:57 21581 8192000000 9770759488 10079625216 845249904 9.352 100 96 192 0 882341744 14421760 22670080
11:11:27 15677 8192000000 9804395856 10123657216 866189888 9.562 100 80 160 0 907471648 22711280 18570480
11:12:48 14041 8192000000 9872084048 10202025984 910854048 10.006 100 82 164 0 976354624 47210368 18290208
11:14:05 8093 8192000000 9911832112 10251948032 950005904 10.391 100 86 171 1 1021428240 52912800 18509536
11:15:17 2730 8192000000 9943833936 10297024512 985439776 10.737 100 86 162 0 1058479376 56339760 16699840
11:16:22 1467 8192000000 10006586720 10364923904 1011710928 10.992 100 96 180 0 1122798080 93507408 17579744
11:17:25 1130 8192000000 10004606832 10372276224 1030870576 11.177 100 91 178 0 1124394368 75049984 18473808
11:18:28 807 8192000000 10021089008 10396581888 1056602416 11.424 100 96 182 1 1140537200 66247840 17686944
11:19:30 4272 8192000000 10024195104 10400083968 1063147888 11.487 100 97 184 1 1146085024 62635360 20301776
11:20:35 1885 8192000000 10038426816 10415521792 1075598112 11.606 100 94 181 0 1164063488 70134880 18330496
11:21:38 1315 8192000000 10052327296 10433175552 1084754304 11.693 100 92 172 1 1172157536 70140656 17262576
11:22:40 2034 8192000000 10049822992 10443358208 1083463744 11.680 100 75 148 0 1173691728 75495840 14732144
11:23:43 1056 8192000000 10050065904 10440806400 1087611216 11.720 100 83 161 1 1172204032 67717056 16875760
11:24:46 1094 8192000000 10057557216 10446540800 1096925648 11.808 100 95 183 0 1179614768 64070176 18618944
11:25:48 1738 8192000000 10056264032 10450935808 1100486048 11.842 100 77 149 1 1180847712 65351504 15010160
11:26:52 1188 8192000000 10060986752 10455629824 1103211952 11.868 100 94 178 1 1189037232 67393040 18432240
11:27:53 1437 8192000000 10038592768 10426200064 1099289152 11.831 100 85 168 3 1157088576 40444064 17355360
11:28:56 2761 8192000000 10035849408 10424627200 1083605600 11.682 99 83 151 2 1148154848 49095888 15453360
11:29:59 1053 8192000000 10029753088 10411368448 1071754048 11.569 100 91 178 0 1148438368 60073680 16610640
11:31:01 1302 8192000000 10032800336 10413199360 1065723616 11.511 100 96 174 0 1155687600 71021904 18942080
11:32:04 2393 8192000000 10017543792 10405457920 1067905392 11.532 99 85 157 3 1137614064 54099872 15608800
11:33:08 4029 8192000000 9994789584 10376425472 1050640176 11.367 100 78 138 2 1119105712 54832096 13633440
11:34:13 3722 8192000000 9994736144 10373414912 1038442656 11.250 98 79 144 1 1104110704 49732512 15935536
11:35:17 8830 8192000000 9988892112 10373455872 1047351200 11.335 100 61 121 0 1116517504 57293792 11872512
11:36:28 7992 8192000000 9980812720 10356453376 1036030624 11.226 100 70 135 3 1106907712 56647456 14229632
11:37:41 12266 8192000000 10002820320 10388262912 1055862384 11.417 99 67 126 0 1116587360 47063712 13661264
11:39:01 1569 8192000000 10033447936 10414305280 1078048048 11.629 100 84 157 2 1153921728 60719376 15154304
11:40:08 1203 8192000000 10073188288 10451595264 1078128224 11.630 100 91 179 1 1190519440 94173216 18218000
11:41:13 1963 8192000000 10079756864 10466119680 1099886672 11.837 100 68 135 1 1206394688 92321904 14186112
11:42:22 2452 8192000000 10098864304 10485391360 1105447456 11.889 100 84 157 3 1212897552 90665328 16784768
11:43:29 4893 8192000000 10128896288 10522419200 1116664560 11.995 100 73 139 3 1247978128 116901952 14411616
11:44:41 5528 8192000000 10131371984 10527432704 1118952288 12.017 100 65 120 1 1253912000 121731584 13228128
11:45:53 13505 8192000000 10178203072 10575388672 1127687104 12.100 100 67 132 0 1294585344 151732000 15166240
11:47:11 3877 8192000000 10240088208 10635599872 1150753520 12.317 100 77 154 0 1359778016 192319152 16705344
11:48:20 2157 8192000000 10272664992 10672517120 1165660160 12.456 100 83 164 0 1391852224 207679680 18512384
11:49:25 1948 8192000000 10297087984 10707636224 1168933472 12.487 100 87 171 0 1406422736 219270512 18218752
11:50:31 1394 8192000000 10327120112 10744676352 1173601072 12.530 100 98 194 1 1437989680 241948832 22439776
11:51:36 1667 8192000000 10356126368 10758664192 1182616208 12.615 100 93 179 1 1465361520 261940912 20804400
11:52:42 1238 8192000000 10418023280 10826215424 1180789680 12.598 100 94 179 3 1522941776 320069648 22082448
11:53:47 827 8192000000 10426774768 10852048896 1194389008 12.724 100 95 188 0 1535461328 318285136 22787184
11:54:50 990 8192000000 10455481344 10864390144 1204893040 12.822 100 95 184 2 1564494672 337606864 21994768
11:55:59 2373 8192000000 10490649216 10914971648 1206442688 12.836 100 95 189 1 1596448432 368034512 21971232
11:57:04 1920 8192000000 10512682144 10941743104 1205104272 12.824 100 96 190 1 1619406576 391318752 22983552
11:58:10 1018 8192000000 10514778896 10943266816 1210251056 12.871 100 81 158 1 1619691408 390209776 19230576
11:59:22 2025 8192000000 10557153824 10974019584 1205494816 12.827 100 77 152 1 1664736528 439944224 19297488
12:00:31 1478 8192000000 10586406912 11031580672 1199357248 12.770 100 87 170 0 1687853872 467788448 20708176
12:01:37 1974 8192000000 10546902048 11009597440 1174910768 12.543 100 72 140 0 1655077456 462162832 18003856
12:02:43 2397 8192000000 10530120816 10968588288 1183029376 12.618 100 58 116 0 1641040592 443776512 14234704
12:03:56 18812 8192000000 10564392656 11002884096 1193049632 12.712 100 45 90 0 1668484608 463634160 11800816
12:05:23 20963 8192000000 10545442128 11000922112 1177993568 12.571 99 41 79 0 1653452336 465387008 10071760
12:06:46 16084 8192000000 10527958800 11015970816 1170331424 12.500 99 33 66 0 1610618704 430928704 9358576
12:08:19 19456 8192000000 10444274816 10936311808 1167957904 12.478 99 32 62 2 1562654960 387090096 7606960
12:09:54 16827 8192000000 10320087744 10756460544 1131722976 12.138 98 32 61 1 1411572736 272142112 7707648
12:11:15 2359 8192000000 10306072992 10775740416 1161232880 12.415 100 80 151 1 1404482096 227395936 15853280
12:12:18 1215 8192000000 10276708240 10794188800 1145813824 12.270 100 95 190 0 1370330528 203045600 21471104
12:13:21 28222 8192000000 10253351264 10672123904 1156990560 12.375 100 53 99 0 1349080464 181242544 10847360
12:14:50 25200 8192000000 10206674544 10695012352 1164957216 12.450 100 22 44 1 1347053520 176731088 5365216
12:16:15 15417 8192000000 10201909168 10626686976 1165306784 12.453 100 59 115 1 1297180384 118423024 13450576
12:17:35 40774 8192000000 10136703936 10673082368 1154770864 12.354 100 13 26 2 1274387216 116777472 2838880
12:19:48 68549 8192000000 10111178624 10532925440 1137352944 12.191 98 20 36 1 1173885200 31744256 4788000
12:22:08 62962 8192000000 10103232224 10528657408 1115720704 11.987 96 46 88 1 1164092720 40286272 8085744
12:24:21 82183 8192000000 10097135520 10551566336 1119424832 12.022 98 9 18 1 1156157072 34963696 1768544
12:26:43 83365 8192000000 10108248640 10545074176 1126329696 12.087 98 47 91 1 1187218144 50669760 10218688
12:29:12 48331 8192000000 10113117616 10546950144 1141977920 12.234 100 38 74 1 1215292448 64016224 9298304
12:31:09 1736 8192000000 10167165056 10592374784 1151057488 12.319 100 83 159 4 1261328912 92444464 17826960
12:32:12 34085 8192000000 10133173248 10572316672 1123087552 12.056 98 34 65 0 1227930496 97699264 7143680
12:33:50 26936 8192000000 10132160928 10555092992 1117986736 12.008 99 40 76 2 1219010448 92165888 8857824
12:35:20 4581 8192000000 10145968880 10572513280 1131337040 12.134 100 60 120 1 1236335072 91796560 13201472
12:36:25 41873 8192000000 10119840016 10561507328 1122508480 12.051 100 16 32 0 1215485344 89264752 3712112
12:38:08 31521 8192000000 10055088176 10541223936 1086928016 11.713 96 15 28 0 1156476352 66694432 2853904
12:39:40 74610 8192000000 10066090128 10531942400 1132365184 12.144 100 21 42 0 1188456496 51351664 4739648
12:42:38 74771 8192000000 10069787808 10518765568 1107197568 11.906 98 19 36 1 1153126160 42044768 3883824
12:45:03 8533 8192000000 10080703408 10514374656 1119801072 12.025 99 49 92 1 1167741264 38812064 9128128
12:46:14 7360 8192000000 10100270800 10537832448 1139901856 12.215 100 42 81 1 1207855312 60019872 7933584
12:47:22 18714 8192000000 10146225968 10611941376 1145127376 12.264 100 33 66 1 1237902512 86237120 6538016
12:48:41 21553 8192000000 10066571216 10507825152 1129600000 12.118 100 51 98 1 1171186672 30881360 10705312
12:50:07 8314 8192000000 10100335888 10529660928 1128687120 12.109 100 59 112 1 1189963552 50321472 10954960
12:51:19 46210 8192000000 10114332048 10580369408 1113946720 11.970 98 32 63 0 1213935776 93395456 6593600
12:53:12 56459 8192000000 10065825696 10532134912 1071434576 11.566 94 29 56 1 1117111776 40384112 5293088
12:55:20 31474 8192000000 10121021904 10550595584 1126641168 12.090 100 39 77 2 1205648448 71010192 7997088
12:56:53 75398 8192000000 10073207680 10514972672 1128779824 12.110 100 21 42 1 1199778672 66702464 4296384
12:59:21 28037 8192000000 10095832928 10530377728 1132908752 12.149 100 49 98 0 1191693200 49211504 9572944
13:01:09 48265 8192000000 10113445648 10583658496 1117457968 12.003 99 42 84 0 1214945008 88936016 8551024
13:02:58 44239 8192000000 10120384976 10565533696 1129746624 12.119 99 25 49 0 1214494448 78860432 5887392
13:04:49 69338 8192000000 10154255808 10628935680 1130875760 12.130 100 12 24 1 1254972128 121311872 2784496
13:06:59 50269 8192000000 10053424912 10570186752 1112352640 11.955 99 20 40 0 1197801168 81150512 4298016
13:08:49 32972 8192000000 10113003648 10568450048 1143843552 12.252 100 52 100 1 1200616336 46277056 10495728
13:10:26 26189 8192000000 10168207632 10605981696 1153720848 12.344 100 44 87 0 1248048240 85122064 9205328
13:12:01 64217 8192000000 10130421664 10630578176 1153445456 12.342 100 15 30 0 1257811744 100575376 3790912
13:14:06 7750 8192000000 10103941264 10534129664 1158306000 12.387 100 67 128 3 1205254304 32354704 14593600
13:15:17 48728 8192000000 10115185424 10585354240 1132252992 12.143 99 32 62 2 1222971648 84141680 6576976
13:17:08 9189 8192000000 10100534480 10523213824 1132645312 12.146 100 64 118 1 1180422432 34879936 12897184
13:18:19 34136 8192000000 10134176032 10564136960 1107791088 11.911 97 33 66 1 1183083168 68679776 6612304
13:20:20 143976 8192000000 10063195344 10498805760 1105796544 11.893 98 22 43 0 1182964752 72167824 5000384
13:23:45 35317 8192000000 10080203616 10542604288 1132194304 12.142 100 37 72 1 1211524320 71936768 7393248
13:25:24 57755 8192000000 10061336576 10520838144 1125494112 12.079 99 11 22 0 1157082832 29362224 2226496
13:27:22 50908 8192000000 10067991440 10522431488 1117389936 12.002 98 22 45 1 1153914672 31538704 4986032
13:30:14 57079 8192000000 10085170896 10530021376 1111059280 11.942 98 17 34 0 1156599216 41785056 3754880
13:32:42 25519 8192000000 10102628992 10540077056 1106309024 11.897 99 33 65 2 1195868944 82712624 6847296
13:34:09 37116 8192000000 10052439664 10492248064 1089121344 11.734 97 26 52 1 1125263856 30845344 5297168
13:35:46 40204 8192000000 10112781008 10547617792 1091977328 11.761 97 38 75 1 1173949504 73978800 7993376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 01-04-2022 13:38:41 to 01-04-2022 13:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-04-2022 13:38:59 to 01-04-2022 13:39:01.

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          215112
MON$OLDEST_ACTIVE               219620
MON$OLDEST_SNAPSHOT             219620
MON$NEXT_TRANSACTION            219620
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                       2077024
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 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 ---
:344: 
:345: mscs-test-161.budzdorov.ru    Fri Apr  1 13:37:25 2022
:346:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:347: 
--- end of diff output ---

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

01.04.22 13:39:02. 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-04-01 13:39:04 No severe PSQL-related problems occured
01.04.22 13:39:06. End of report.