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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16462324 kB
MemAvailable 31359224 kB
Buffers 0 kB
Cached 15265472 kB
SwapCached 1208 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.1
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-01-07 10:37:12
DTS_END                         2022-01-07 10:37:12.4920
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/38464
TRN_ID                          217781
ATT_ID                          575
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: 8685.42 23070 1042251
customer order: creation 169.69 456 20363
customer order: refuse 13.62 404 1634
order to supplier: creation 44.24 1445 5309
order to supplier: removal 3.23 156154 388
invoice (draft): creation 46.06 3329 5527
invoice (draft): removal 46.55 1064 5586
invoice accept: apply 41.32 27376 4958
invoice accept: cancel 41.83 1308 5020
customer reserve: creation 3827.58 262 459309
customer reserve: removal 4171.99 94 500639
realization accept: apply 81.33 284 9759
realization accept: cancel 73.19 159 8783
payment from customer: creation 57.18 178 6861
payment from customer: removal 5.61 126 673
payment to supplier: creation 46.57 196 5588
payment to supplier: removal 3.11 115 373
service: total inventory turnovers 3.79 4015 455
service: total monetary turnovers 7.57 388 908
service: aggregate perf. data 0.95 15648 114
service: refresh index statistics 0.03 248391 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-01-2022 10:39:53 to 07-01-2022 10:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8709
TEST_TIME 2 8271
TEST_TIME 3 8642
TEST_TIME 4 7695
TEST_TIME 5 7963
TEST_TIME 6 8183
TEST_TIME 7 8077
TEST_TIME 8 7786
TEST_TIME 9 7783
TEST_TIME 10 7669
TEST_TIME 11 7683
TEST_TIME 12 7589
TEST_TIME 13 7727
TEST_TIME 14 7730
TEST_TIME 15 7880
TEST_TIME 16 7748
TEST_TIME 17 7742
TEST_TIME 18 7604
TEST_TIME 19 7653
TEST_TIME 20 7820
TEST_TIME 21 8047
TEST_TIME 22 8355
TEST_TIME 23 8537
TEST_TIME 24 8739
TEST_TIME 25 8783
TEST_TIME 26 9253
TEST_TIME 27 9362
TEST_TIME 28 9618
TEST_TIME 29 9902
TEST_TIME 30 10351
TEST_TIME 31 10218
TEST_TIME 32 10676
TEST_TIME 33 11065
TEST_TIME 34 10754
TEST_TIME 35 10481
TEST_TIME 36 10481
TEST_TIME 37 10458
TEST_TIME 38 10325
TEST_TIME 39 10314
TEST_TIME 40 10192
TEST_TIME 41 10138
TEST_TIME 42 10051
TEST_TIME 43 9996
TEST_TIME 44 9904
TEST_TIME 45 9762
TEST_TIME 46 9660
TEST_TIME 47 9698
TEST_TIME 48 9561
TEST_TIME 49 9459
TEST_TIME 50 9354
TEST_TIME 51 9292
TEST_TIME 52 9247
TEST_TIME 53 9169
TEST_TIME 54 9148
TEST_TIME 55 9195
TEST_TIME 56 9174
TEST_TIME 57 9076
TEST_TIME 58 8964
TEST_TIME 59 9020
TEST_TIME 60 9008
TEST_TIME 61 8909
TEST_TIME 62 8995
TEST_TIME 63 9027
TEST_TIME 64 9031
TEST_TIME 65 9055
TEST_TIME 66 9151
TEST_TIME 67 9154
TEST_TIME 68 9127
TEST_TIME 69 9195
TEST_TIME 70 9230
TEST_TIME 71 9227
TEST_TIME 72 9225
TEST_TIME 73 9249
TEST_TIME 74 9202
TEST_TIME 75 9137
TEST_TIME 76 9134
TEST_TIME 77 9080
TEST_TIME 78 9034
TEST_TIME 79 9058
TEST_TIME 80 9013
TEST_TIME 81 8956
TEST_TIME 82 8904
TEST_TIME 83 8896
TEST_TIME 84 8895
TEST_TIME 85 8864
TEST_TIME 86 8905
TEST_TIME 87 8911
TEST_TIME 88 8880
TEST_TIME 89 8888
TEST_TIME 90 8923
TEST_TIME 91 8910
TEST_TIME 92 8902
TEST_TIME 93 8986
TEST_TIME 94 8993
TEST_TIME 95 8994
TEST_TIME 96 9021
TEST_TIME 97 8988
TEST_TIME 98 9026
TEST_TIME 99 8958
TEST_TIME 100 8953
TEST_TIME 101 8895
TEST_TIME 102 8889
TEST_TIME 103 8839
TEST_TIME 104 8799
TEST_TIME 105 8763
TEST_TIME 106 8726
TEST_TIME 107 8686
TEST_TIME 108 8661
TEST_TIME 109 8705
TEST_TIME 110 8658
TEST_TIME 111 8687
TEST_TIME 112 8709
TEST_TIME 113 8685
TEST_TIME 114 8749
TEST_TIME 115 8776
TEST_TIME 116 8756
TEST_TIME 117 8763
TEST_TIME 118 8745
TEST_TIME 119 8779
TEST_TIME 120 8713

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-01-2022 10:39:56 to 07-01-2022 10:40:00.

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 1465082 1465082 0 0.00 0 20450 14 0 0
doc_list_biud 1465067 1465067 0 0.00 0 34942 38 0 0
sp_add_invoice_to_stock 5056 4958 98 1.93 43 213217 27376 0 98
sp_cancel_adding_invoice 5020 5020 0 0.00 7 100667 1309 0 0
sp_cancel_client_order 1634 1634 0 0.00 6 12866 404 0 0
sp_cancel_customer_reserve 500639 500639 0 0.00 1 24294 95 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 6324 126 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 4 2897 115 0 0
sp_cancel_supplier_invoice 5601 5586 15 0.26 4 34949 1065 0 15
sp_cancel_supplier_order 388 388 0 0.00 11 1245543 156154 0 0
sp_cancel_write_off 8783 8783 0 0.00 2 10973 160 0 0
sp_client_order 20363 20363 0 0.00 5 66246 457 0 0
sp_customer_reserve 459309 459309 0 0.00 1 63716 262 0 0
sp_fill_shopping_cart 31197 31197 0 0.00 1 29015 83 0 0
sp_fill_shopping_cart_clo_res 459274 459274 0 0.00 0 12813 18 0 0
sp_get_clo_for_invoice 4906 4906 0 0.00 0 23716 318 0 0
sp_kill_cost_storno 14849 14849 0 0.00 0 18005 74 0 0
sp_kill_qstorno_ret_qs2qd 1016035 1016035 0 0.00 0 34940 42 0 0
sp_kill_qty_storno 536713 536713 0 0.00 0 34942 97 0 0
sp_lock_selected_doc 20110 20110 0 0.00 0 8463 6 0 0
sp_make_cost_storno 27114 27114 0 0.00 0 17985 86 0 0
sp_multiply_rows_for_pdistr 27112 27112 0 0.00 0 9739 37 0 0
sp_multiply_rows_for_qdistr 20363 20363 0 0.00 0 11798 175 0 0
sp_pay_from_customer 6861 6861 0 0.00 5 14758 179 0 0
sp_pay_to_supplier 5588 5588 0 0.00 6 20386 196 0 0
sp_payment_common 12449 12449 0 0.00 2 8388 43 0 0
sp_qd_handle_on_cancel_clo 1634 1634 0 0.00 1 12859 333 0 0
sp_qd_handle_on_invoice_upd_sts 9924 9924 0 0.00 0 22543 521 0 0
sp_qd_handle_on_reserve_upd_sts 9759 9759 0 0.00 0 17381 58 0 0
sp_reserve_write_off 9759 9759 0 0.00 8 30774 284 0 0
sp_supplier_invoice 5527 5527 0 0.00 10 100697 3329 0 0
sp_supplier_order 5309 5309 0 0.00 8 33872 1445 0 0
srv_aggregate_perf_data 114 114 0 0.00 2217 168673 15648 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 825 150390 36479 0 0
srv_find_qd_qs_mism 994159 994159 0 0.00 0 20321 12 0 0
srv_make_invnt_saldo 455 455 0 0.00 100 94404 4016 0 0
srv_make_money_saldo 908 908 0 0.00 1 12010 389 0 0
srv_recalc_idx_stat 4 4 0 0.00 44997 427395 248392 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4962 4962 0 0.00 0 13317 993 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 8545 522 0 0
t$perf-norm:sp_cancel_client_order 1634 1634 0 0.00 0 1958 27 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 2297 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 4269 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 1238 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 2576 48 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 110039 6754 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 1305 20 0 0
t$perf-norm:sp_client_order 20363 20363 0 0.00 0 12838 38 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 5737 26 0 0
t$perf-norm:sp_pay_from_customer 6861 6861 0 0.00 0 4515 20 0 0
t$perf-norm:sp_pay_to_supplier 5588 5588 0 0.00 0 10179 22 0 0
t$perf-norm:sp_reserve_write_off 9759 9759 0 0.00 0 14157 30 0 0
t$perf-norm:sp_supplier_invoice 5527 5527 0 0.00 0 13074 37 0 0
t$perf-norm:sp_supplier_order 5309 5309 0 0.00 0 10290 36 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 610 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 162 7 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 782 13 0 0
t$perf-norm:srv_make_money_saldo 908 908 0 0.00 0 4266 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 185 52 0 0
v_add_invoice_to_stock 10004 9808 196 1.95 0 3418 23 0 196
v_all_customers 36776 36776 0 0.00 0 61 4 0 0
v_all_suppliers 30070 30070 0 0.00 0 127 4 0 0
v_all_wares 40726 40726 0 0.00 0 90 4 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 3432 22 0 0
v_cancel_client_order 3268 3268 0 0.00 0 3644 13 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 2990 13 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 1 2084 12 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 1376 11 0 0
v_cancel_supplier_invoice 832 802 30 3.60 0 439 21 0 30
v_cancel_supplier_order 776 776 0 0.00 0 1434 12 0 0
v_cancel_write_off 1858 1858 0 0.00 0 2418 24 0 0
v_max_id_clo_ord 5309 5309 0 0.00 0 460 5 0 0
v_max_id_clo_res 985 985 0 0.00 0 3573 18 0 0
v_max_id_ord_sup 5525 5525 0 0.00 0 6061 7 0 0
v_max_non_paid_invoice 1387 1387 0 0.00 0 61 7 0 0
v_max_non_paid_realizn 3669 3669 0 0.00 0 699 6 0 0
v_min_id_clo_ord 5309 5309 0 0.00 0 2893 9 0 0
v_min_id_clo_res 985 985 0 0.00 0 6840 23 0 0
v_min_id_ord_sup 5525 5525 0 0.00 0 12344 15 0 0
v_min_non_paid_invoice 5588 5588 0 0.00 0 3977 13 0 0
v_min_non_paid_realizn 6861 6861 0 0.00 0 8382 16 0 0
v_our_firm 10334 10334 0 0.00 0 65 4 0 0
v_reserve_write_off 19518 19518 0 0.00 0 3199 12 0 0
x_lock_depdocs_on_canc_invoice 10205 10205 0 0.00 0 14350 131 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 3392 315 0 0
x_make_qstorno_1000_1200 5309 5309 0 0.00 2 33852 1287 0 0
x_make_qstorno_1200_2000 5527 5527 0 0.00 5 100681 3096 0 0
x_make_qstorno_2100_3300 459309 459309 0 0.00 0 63712 231 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2022 10:40:01 to 07-01-2022 10:40:05.

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:16 17 8192000000 8802335968 8815104000 5319440 0.064 3 1 2 0 5420608 65392 35776
08:08:16 24674 8192000000 9718211856 10014318592 799255216 8.889 100 87 174 0 828467888 8111792 21100880
08:09:47 15132 8192000000 9762413488 10068602880 838944656 9.289 100 86 172 1 871432896 12121264 20366976
08:11:10 17145 8192000000 9809320992 10127192064 876164896 9.661 100 88 176 1 915763472 19687504 19911072
08:12:35 10404 8192000000 9878793264 10211713024 920584208 10.102 100 85 170 0 986334208 47669376 18080624
08:13:47 6421 8192000000 9933486752 10276667392 965276832 10.541 100 87 173 0 1046557072 63137104 18143136
08:14:57 1572 8192000000 9977736144 10327379968 994870640 10.829 100 92 179 1 1097325808 84097472 18357696
08:16:02 1963 8192000000 9998648464 10357006336 1015182096 11.025 100 88 175 0 1109110304 76258464 17669744
08:17:06 801 8192000000 10036637392 10401026048 1039016704 11.255 100 98 190 0 1156321376 98219152 19085520
08:18:08 7113 8192000000 10005572928 10372968448 1055652480 11.415 100 94 182 2 1124464144 50554640 18257024
08:19:16 2507 8192000000 10049400448 10432856064 1068170192 11.535 100 92 178 1 1166881696 79801680 18909824
08:20:21 1928 8192000000 10061573744 10441011200 1072582032 11.577 100 88 173 1 1179132448 90160096 16390320
08:21:24 1243 8192000000 10037847552 10417238016 1082563072 11.672 100 89 162 1 1159653088 62196288 14893728
08:22:26 5118 8192000000 10040419520 10437492736 1082595984 11.672 100 87 174 0 1173767520 73883088 17288448
08:23:32 1213 8192000000 10052135840 10435874816 1089216496 11.735 100 97 189 0 1173532464 65233968 19082000
08:24:34 1417 8192000000 10085567344 10476978176 1090840464 11.751 100 95 183 2 1197862864 88443648 18578752
08:25:36 995 8192000000 10046749200 10431819776 1093501440 11.776 100 92 175 1 1170421968 58318320 18602208
08:26:38 1160 8192000000 10064684336 10446147584 1088958688 11.733 100 95 189 1 1189411360 80653264 19799408
08:27:41 11560 8192000000 10038557888 10434273280 1086610032 11.710 100 60 112 1 1153601888 56350656 10641200
08:28:53 1230 8192000000 10014988272 10404274176 1082564400 11.672 100 69 134 1 1135275728 38933184 13778144
08:29:56 1132 8192000000 10006714320 10386972672 1062285520 11.478 99 91 171 1 1126687712 46903648 17498544
08:31:00 1275 8192000000 9985645024 10360700928 1049447696 11.355 100 87 165 1 1108276816 40046592 18782528
08:32:02 1887 8192000000 9977950080 10348515328 1028991088 11.159 99 81 157 2 1095795856 51272816 15531952
08:33:12 13140 8192000000 10010788912 10398134272 1033181424 11.199 100 52 102 0 1117811904 74370320 10260160
08:34:30 937 8192000000 9974379024 10342076416 1007561008 10.952 100 87 158 1 1092408592 67558080 17289504
08:35:31 13985 8192000000 9947485776 10324914176 1018798032 11.060 99 52 93 1 1075609568 47733136 9078400
08:36:46 4385 8192000000 9988516016 10360823808 1032360640 11.191 100 75 137 3 1099488944 54031088 13097216
08:37:56 5750 8192000000 10001124192 10379419648 1057229696 11.430 100 72 131 0 1131045920 60538144 13278080
08:39:20 1681 8192000000 10038021264 10422480896 1068639488 11.539 100 77 137 3 1158797792 76100688 14057616
08:40:26 905 8192000000 10054247664 10438709248 1079485552 11.643 100 83 152 1 1173685456 79036832 15163072
08:41:31 6213 8192000000 10062219344 10456719360 1094889472 11.789 100 73 141 1 1185706048 75848384 14968192
08:42:42 1147 8192000000 10131685392 10516299776 1118104784 12.009 100 85 167 2 1246570960 111805280 16660896
08:43:46 1323 8192000000 10135382800 10525233152 1132557568 12.145 100 89 174 5 1249235904 97721840 18956496
08:44:51 5553 8192000000 10151609280 10550317056 1140083648 12.216 100 73 135 1 1269810352 114637504 15089200
08:46:04 1157 8192000000 10204134128 10595491840 1150765600 12.317 100 98 190 1 1317740864 146540384 20434880
08:47:08 1360 8192000000 10237474096 10630107136 1150194368 12.311 100 91 183 2 1346797712 176703424 19899920
08:48:12 3493 8192000000 10267702624 10670329856 1158473776 12.389 100 75 150 0 1384949296 209984208 16491312
08:49:19 819 8192000000 10306644976 10706001920 1175905056 12.552 100 91 178 0 1418806848 222719792 20182000
08:50:23 1179 8192000000 10337775552 10732666880 1181505728 12.604 100 88 173 0 1444422720 243156880 19760112
08:51:26 1878 8192000000 10360217904 10762481664 1182714720 12.616 100 89 169 1 1476862928 273927360 20220848
08:52:31 1097 8192000000 10392705008 10802163712 1191558800 12.698 100 93 180 0 1500432352 288280704 20592848
08:53:37 908 8192000000 10436440400 10834243584 1191465664 12.697 100 97 190 2 1543872624 329846160 22560800
08:54:44 1533 8192000000 10462705472 10877562880 1193547872 12.716 100 89 176 0 1568021792 352814624 21659296
08:55:51 867 8192000000 10488343472 10887798784 1181073664 12.600 100 98 196 0 1594923600 390040704 23809232
08:56:56 4061 8192000000 10465288528 10917470208 1168619472 12.484 100 83 166 0 1562019008 373226640 20172896
08:58:03 1700 8192000000 10482111232 10930315264 1182343712 12.612 100 95 186 0 1590843328 385863520 22636096
08:59:07 1477 8192000000 10485769616 10937135104 1174875568 12.542 100 96 192 0 1595145216 397524576 22745072
09:00:13 1121 8192000000 10515753856 10939682816 1175008352 12.544 100 97 192 1 1621876064 422456832 24410880
09:01:17 3481 8192000000 10459962256 10913783808 1170335536 12.500 100 68 134 2 1566103136 378385920 17381680
09:02:34 7328 8192000000 10496043152 10930085888 1152876864 12.336 99 52 104 2 1587377184 420868944 13631376
09:03:43 19921 8192000000 10410969008 10861056000 1160745952 12.410 100 32 64 1 1513628432 344681088 8201392
09:05:22 12746 8192000000 10325650912 10808238080 1143194208 12.246 100 40 80 0 1443320400 290191184 9935008
09:06:42 9819 8192000000 10297263840 10800988160 1150372304 12.313 100 51 102 0 1436545040 273682064 12490672
09:08:00 43651 8192000000 10270717088 10718969856 1124138624 12.066 98 18 36 0 1366824592 238199424 4486544
09:09:46 27468 8192000000 10153115888 10652856320 1133108976 12.151 100 29 56 2 1299988400 161031856 5847568
09:11:26 72403 8192000000 10164244896 10592739328 1133604672 12.155 100 53 95 1 1228862992 85216160 10042160
09:14:03 14014 8192000000 10110245952 10571624448 1134770992 12.166 100 44 82 2 1214320784 70807296 8742496
09:15:22 77964 8192000000 10086050368 10559459328 1104483360 11.880 97 16 30 1 1164211488 56521008 3207120
09:18:03 94085 8192000000 10093402320 10532388864 1091098160 11.753 96 24 48 1 1166734048 71033344 4602544
09:20:42 25321 8192000000 10090452208 10525675520 1133116336 12.151 100 44 86 3 1175010800 32781616 9112848
09:22:16 16483 8192000000 10093138304 10525233152 1135887024 12.177 100 45 87 2 1202696624 57591872 9217728
09:23:35 143938 8192000000 10035318848 10471108608 1117599824 12.004 99 24 47 2 1197156912 74163136 5393952
09:27:02 77654 8192000000 10097941808 10531155968 1134538096 12.164 100 33 66 0 1198614752 57212832 6863824
09:29:25 19108 8192000000 10074073536 10535321600 1134640896 12.165 100 25 48 1 1182109552 42633520 4835136
09:30:45 66697 8192000000 10128094064 10616926208 1123033856 12.056 98 22 45 1 1212834592 85330736 4470000
09:32:52 99775 8192000000 10100951520 10612289536 1141355104 12.228 100 10 20 1 1195226208 51694752 2176352
09:36:11 97605 8192000000 10096979072 10603094016 1121912528 12.045 98 8 16 1 1169439488 45869360 1657600
09:38:50 59288 8192000000 10109567056 10562420736 1113942448 11.970 96 25 48 3 1192208336 73559056 4706832
09:40:52 119644 8192000000 10092354720 10583388160 1109295776 11.926 96 9 18 0 1169128368 58014016 1818576
09:44:11 74921 8192000000 10124512384 10562785280 1140709776 12.222 99 43 82 2 1192395552 42600800 9084976
09:47:07 82384 8192000000 10067644720 10539331584 1091496352 11.757 95 16 32 0 1137415440 42719360 3199728
09:49:42 54123 8192000000 10111643664 10605510656 1125036176 12.075 98 12 25 0 1209304368 81566560 2701632
09:51:36 150390 8192000000 10094210928 10569814016 1135305824 12.171 99 28 56 0 1205612256 64203280 6103152
09:55:07 106857 8192000000 10062365696 10563403776 1113082560 11.962 97 8 16 0 1171324032 56528096 1713376
09:57:56 50080 8192000000 10090048832 10525470720 1121910928 12.045 98 21 42 1 1172227808 45508160 4808720
09:59:47 40006 8192000000 10096738928 10548645888 1126387344 12.087 99 14 28 1 1202719392 73003632 3328416
10:01:27 85987 8192000000 10095752720 10529083392 1102152112 11.858 97 19 38 2 1176635696 70427152 4056432
10:04:05 14355 8192000000 10058315232 10480705536 1109554304 11.928 99 57 107 1 1155321408 33654048 12113056
10:05:23 21906 8192000000 10049407552 10469490688 1065159360 11.506 96 33 65 1 1121662016 50008336 6494320
10:06:48 19539 8192000000 10083698400 10496831488 1092815120 11.769 98 51 99 1 1153350784 50741248 9794416
10:08:08 19536 8192000000 10055100992 10472304640 1096511200 11.805 98 53 105 3 1135882672 29048288 10323184
10:09:30 65357 8192000000 10127814080 10594562048 1099030416 11.828 98 21 42 0 1188521344 85134016 4356912
10:11:36 40546 8192000000 10071477104 10515890176 1086111472 11.706 97 38 76 0 1146322496 52693920 7517104
10:13:55 22541 8192000000 10081046336 10504081408 1111298720 11.945 99 45 88 3 1154602144 34227568 9075856
10:15:18 66040 8192000000 10104615280 10570891264 1128074544 12.103 100 32 64 0 1218619056 83834720 6709792
10:18:14 14127 8192000000 10047193232 10497392640 1109976112 11.932 99 41 70 3 1139729440 22526352 7226976
10:19:31 51309 8192000000 10040692928 10499207168 1127944064 12.102 100 6 13 0 1152478368 23576048 958256
10:21:23 68798 8192000000 10071814128 10519367680 1130471536 12.126 100 23 46 2 1175081904 39356416 5253952
10:25:26 17200 8192000000 10141689536 10579058688 1153656800 12.344 100 27 54 0 1236632992 76714240 6261952
10:26:47 64320 8192000000 10134557696 10556329984 1123136080 12.057 98 33 66 1 1196691056 65891520 7663456
10:28:54 47679 8192000000 10104121200 10534301696 1126546272 12.089 99 25 50 1 1186710976 55051136 5113568
10:30:45 38393 8192000000 10106396688 10605527040 1120566128 12.032 98 30 58 1 1217451072 89939968 6944976
10:32:31 54226 8192000000 10103547120 10541760512 1113569104 11.966 98 47 94 1 1169314016 46575024 9169888
10:34:30 11597 8192000000 10106431616 10528821248 1120254544 12.029 99 44 86 3 1198964016 68777968 9931504
10:35:43 44471 8192000000 10087937504 10525745152 1132555968 12.145 100 48 96 1 1200266640 57914560 9796112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-01-2022 10:40:07 to 07-01-2022 10:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2022 10:40:23 to 07-01-2022 10:40:25.

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          213898
MON$OLDEST_ACTIVE               217892
MON$OLDEST_SNAPSHOT             217892
MON$NEXT_TRANSACTION            217892
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                       2048480
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2704 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2704 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2704 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2704 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 ---
:135: 
:136: mscs-test-161.budzdorov.ru    Fri Jan  7 10:37:16 2022
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:138: 
:139: 
:140: mscs-test-161.budzdorov.ru    Fri Jan  7 10:37:16 2022
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
--- end of diff output ---

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

07.01.22 10:40:26. 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-01-07 10:40:29 No severe PSQL-related problems occured
07.01.22 10:40:30. End of report.