Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.08.2021 07:51
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16191620 kB
MemAvailable 30930584 kB
Buffers 0 kB
Cached 15484012 kB
SwapCached 1308 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 2021-08-26 07:50:25
DTS_END                         2021-08-26 07:50:25.3940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35134
TRN_ID                          232802
ATT_ID                          629
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: 8832.84 23824 1059941
customer order: creation 167.72 400 20126
customer order: refuse 14.62 304 1754
order to supplier: creation 45.12 1411 5414
order to supplier: removal 3.42 112203 410
invoice (draft): creation 44.79 3546 5375
invoice (draft): removal 49.83 863 5979
invoice accept: apply 40.83 19420 4900
invoice accept: cancel 44.51 861 5341
customer reserve: creation 3742.16 193 449059
customer reserve: removal 4397.95 69 527754
realization accept: apply 83.18 180 9981
realization accept: cancel 73.63 128 8836
payment from customer: creation 58.28 111 6994
payment from customer: removal 5.64 93 677
payment to supplier: creation 47.43 107 5691
payment to supplier: removal 3.12 133 374
service: total inventory turnovers 3.88 3686 466
service: total monetary turnovers 5.91 590 709
service: aggregate perf. data 0.80 16330 96
service: refresh index statistics 0.04 315848 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-08-2021 07:51:28 to 26-08-2021 07:51:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2260
TEST_TIME 2 2115
TEST_TIME 3 3124
TEST_TIME 4 4162
TEST_TIME 5 4714
TEST_TIME 6 4683
TEST_TIME 7 5211
TEST_TIME 8 5270
TEST_TIME 9 5549
TEST_TIME 10 5940
TEST_TIME 11 6163
TEST_TIME 12 6330
TEST_TIME 13 6615
TEST_TIME 14 6945
TEST_TIME 15 7340
TEST_TIME 16 7795
TEST_TIME 17 8347
TEST_TIME 18 8494
TEST_TIME 19 8430
TEST_TIME 20 8476
TEST_TIME 21 8550
TEST_TIME 22 9030
TEST_TIME 23 9209
TEST_TIME 24 9554
TEST_TIME 25 9736
TEST_TIME 26 9826
TEST_TIME 27 10474
TEST_TIME 28 10949
TEST_TIME 29 10930
TEST_TIME 30 10699
TEST_TIME 31 10504
TEST_TIME 32 10399
TEST_TIME 33 10237
TEST_TIME 34 10041
TEST_TIME 35 9888
TEST_TIME 36 9771
TEST_TIME 37 9733
TEST_TIME 38 9554
TEST_TIME 39 9373
TEST_TIME 40 9172
TEST_TIME 41 9267
TEST_TIME 42 9208
TEST_TIME 43 9206
TEST_TIME 44 9117
TEST_TIME 45 9123
TEST_TIME 46 9124
TEST_TIME 47 9010
TEST_TIME 48 9337
TEST_TIME 49 9299
TEST_TIME 50 9285
TEST_TIME 51 9282
TEST_TIME 52 9206
TEST_TIME 53 9268
TEST_TIME 54 9389
TEST_TIME 55 9357
TEST_TIME 56 9284
TEST_TIME 57 9328
TEST_TIME 58 9443
TEST_TIME 59 9319
TEST_TIME 60 9238
TEST_TIME 61 9149
TEST_TIME 62 9079
TEST_TIME 63 9071
TEST_TIME 64 9032
TEST_TIME 65 8988
TEST_TIME 66 8961
TEST_TIME 67 8955
TEST_TIME 68 8966
TEST_TIME 69 9045
TEST_TIME 70 9082
TEST_TIME 71 9107
TEST_TIME 72 9056
TEST_TIME 73 9080
TEST_TIME 74 9174
TEST_TIME 75 9127
TEST_TIME 76 9162
TEST_TIME 77 9221
TEST_TIME 78 9264
TEST_TIME 79 9232
TEST_TIME 80 9209
TEST_TIME 81 9194
TEST_TIME 82 9089
TEST_TIME 83 9028
TEST_TIME 84 9013
TEST_TIME 85 9015
TEST_TIME 86 8941
TEST_TIME 87 8876
TEST_TIME 88 8936
TEST_TIME 89 8943
TEST_TIME 90 8926
TEST_TIME 91 8928
TEST_TIME 92 8912
TEST_TIME 93 8940
TEST_TIME 94 8903
TEST_TIME 95 8948
TEST_TIME 96 9071
TEST_TIME 97 9058
TEST_TIME 98 9032
TEST_TIME 99 9036
TEST_TIME 100 9117
TEST_TIME 101 9160
TEST_TIME 102 9099
TEST_TIME 103 9076
TEST_TIME 104 9073
TEST_TIME 105 9046
TEST_TIME 106 9013
TEST_TIME 107 8973
TEST_TIME 108 8928
TEST_TIME 109 8870
TEST_TIME 110 8850
TEST_TIME 111 8866
TEST_TIME 112 8842
TEST_TIME 113 8820
TEST_TIME 114 8849
TEST_TIME 115 8850
TEST_TIME 116 8874
TEST_TIME 117 8897
TEST_TIME 118 8869
TEST_TIME 119 8890
TEST_TIME 120 8834

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-08-2021 07:51:32 to 26-08-2021 07:51:35.

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 1474881 1474881 0 0.00 0 58817 7 0 0
doc_list_biud 1474881 1474881 0 0.00 0 52022 31 0 0
sp_add_invoice_to_stock 4979 4900 79 1.58 33 168822 19420 0 79
sp_cancel_adding_invoice 5341 5341 0 0.00 4 56849 862 0 0
sp_cancel_client_order 1754 1754 0 0.00 4 11218 304 0 0
sp_cancel_customer_reserve 527754 527754 0 0.00 1 52024 70 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 10231 93 0 0
sp_cancel_pay_to_supplier 374 374 0 0.00 3 10403 134 0 0
sp_cancel_supplier_invoice 5990 5979 11 0.18 1 27424 864 0 11
sp_cancel_supplier_order 410 410 0 0.00 6 1086328 112203 0 0
sp_cancel_write_off 8836 8836 0 0.00 1 14347 129 0 0
sp_client_order 20126 20126 0 0.00 4 23689 400 0 0
sp_customer_reserve 449059 449059 0 0.00 0 58293 193 0 0
sp_fill_shopping_cart 30905 30905 0 0.00 1 24675 52 0 0
sp_fill_shopping_cart_clo_res 449057 449057 0 0.00 0 21160 18 0 0
sp_get_clo_for_invoice 4891 4891 0 0.00 0 21761 392 0 0
sp_kill_cost_storno 15228 15228 0 0.00 0 8183 61 0 0
sp_kill_qstorno_ret_qs2qd 1070733 1070733 0 0.00 0 52015 37 0 0
sp_kill_qty_storno 564945 564945 0 0.00 0 52022 80 0 0
sp_lock_selected_doc 20502 20502 0 0.00 0 10395 8 0 0
sp_make_cost_storno 27556 27556 0 0.00 0 58814 57 0 0
sp_multiply_rows_for_pdistr 27556 27556 0 0.00 0 9715 30 0 0
sp_multiply_rows_for_qdistr 20126 20126 0 0.00 0 18819 196 0 0
sp_pay_from_customer 6994 6994 0 0.00 4 25685 111 0 0
sp_pay_to_supplier 5691 5691 0 0.00 6 7401 108 0 0
sp_payment_common 12685 12685 0 0.00 1 9145 21 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 0 11211 279 0 0
sp_qd_handle_on_invoice_upd_sts 10231 10231 0 0.00 0 17696 360 0 0
sp_qd_handle_on_reserve_upd_sts 9981 9981 0 0.00 0 11448 56 0 0
sp_reserve_write_off 9981 9981 0 0.00 7 28058 180 0 0
sp_supplier_invoice 5375 5375 0 0.00 6 92274 3546 0 0
sp_supplier_order 5414 5414 0 0.00 5 56327 1411 0 0
srv_aggregate_perf_data 96 96 0 0.00 2135 157998 16330 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1249 159928 49742 0 0
srv_find_qd_qs_mism 1013051 1013051 0 0.00 0 20579 6 0 0
srv_make_invnt_saldo 466 466 0 0.00 59 94026 3686 0 0
srv_make_money_saldo 709 709 0 0.00 1 23785 590 0 0
srv_recalc_idx_stat 5 5 0 0.00 179517 497138 315848 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 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 4900 4900 0 0.00 0 45469 169 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 22647 152 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 31373 30 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 9940 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 9545 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 374 374 0 0.00 0 345 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 1442 26 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 21452 1347 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 7879 25 0 0
t$perf-norm:sp_client_order 20126 20126 0 0.00 0 19825 21 0 0
t$perf-norm:sp_customer_reserve 891 891 0 0.00 0 17462 39 0 0
t$perf-norm:sp_pay_from_customer 6994 6994 0 0.00 0 31940 16 0 0
t$perf-norm:sp_pay_to_supplier 5691 5691 0 0.00 0 5641 9 0 0
t$perf-norm:sp_reserve_write_off 9981 9981 0 0.00 0 13684 10 0 0
t$perf-norm:sp_supplier_invoice 5375 5375 0 0.00 0 21351 24 0 0
t$perf-norm:sp_supplier_order 5414 5414 0 0.00 0 22488 23 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 76 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1696 38 0 0
t$perf-norm:srv_make_invnt_saldo 466 466 0 0.00 0 224 4 0 0
t$perf-norm:srv_make_money_saldo 709 709 0 0.00 0 828 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9938 9780 158 1.58 0 4218 14 0 158
v_all_customers 36724 36724 0 0.00 0 223 1 0 0
v_all_suppliers 29680 29680 0 0.00 0 43 1 0 0
v_all_wares 40250 40250 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 520 7 0 0
v_cancel_client_order 3508 3508 0 0.00 0 1697 5 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 1120 5 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 432 4 0 0
v_cancel_payment_to_supplier 748 748 0 0.00 0 2303 10 0 0
v_cancel_supplier_invoice 758 736 22 2.90 0 2522 20 0 22
v_cancel_supplier_order 820 820 0 0.00 0 1930 7 0 0
v_cancel_write_off 2016 2016 0 0.00 0 3092 14 0 0
v_max_id_clo_ord 5414 5414 0 0.00 0 753 1 0 0
v_max_id_clo_res 891 891 0 0.00 0 1737 8 0 0
v_max_id_ord_sup 5366 5366 0 0.00 0 4431 5 0 0
v_max_non_paid_invoice 1631 1631 0 0.00 0 358 2 0 0
v_max_non_paid_realizn 3675 3675 0 0.00 0 5811 4 0 0
v_min_id_clo_ord 5414 5414 0 0.00 0 4340 9 0 0
v_min_id_clo_res 891 891 0 0.00 0 10969 20 0 0
v_min_id_ord_sup 5366 5366 0 0.00 0 6908 7 0 0
v_min_non_paid_invoice 5691 5691 0 0.00 0 5534 12 0 0
v_min_non_paid_realizn 6994 6994 0 0.00 0 7872 17 0 0
v_our_firm 10164 10164 0 0.00 0 29 1 0 0
v_reserve_write_off 19962 19962 0 0.00 0 3076 5 0 0
x_lock_depdocs_on_canc_invoice 10952 10952 0 0.00 0 25022 128 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 17244 390 0 0
x_make_qstorno_1000_1200 5414 5414 0 0.00 2 52513 1322 0 0
x_make_qstorno_1200_2000 5375 5375 0 0.00 3 92233 3344 0 0
x_make_qstorno_2100_3300 449059 449059 0 0.00 0 53654 173 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 26-08-2021 07:51:36 to 26-08-2021 07:51:41.

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
05:20:29 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:21:30 36985 8192000000 9744939952 10049671168 825314672 9.152 100 88 176 1 854320304 8055664 20949968
05:23:12 30438 8192000000 9788116208 10098876416 854817232 9.448 100 90 180 0 889582672 13908672 20856768
05:24:47 19758 8192000000 9837644048 10161946624 893022800 9.829 100 85 170 1 942778032 30298640 19456592
05:26:15 15163 8192000000 9894918496 10236936192 940733328 10.300 100 75 149 0 1007523392 51076304 15713760
05:27:32 7401 8192000000 9941242624 10286698496 974334672 10.629 100 76 153 0 1049701376 59773552 15593152
05:28:43 3597 8192000000 9956253264 10317258752 997178272 10.851 100 82 163 1 1071529040 58067088 16283680
05:29:47 3057 8192000000 10005246576 10368155648 1030407472 11.172 100 77 154 0 1122168496 76095392 15665632
05:30:51 12930 8192000000 10006750896 10380570624 1052379616 11.383 100 66 132 0 1114984224 49592352 13012256
05:32:06 8118 8192000000 9987410416 10379665408 1065555760 11.510 100 32 64 2 1093972304 21962336 6454208
05:33:17 3377 8192000000 9994479696 10384474112 1077312944 11.622 100 48 96 1 1119575920 32437232 9825744
05:34:22 9578 8192000000 9997279856 10387554304 1082234544 11.669 100 43 83 1 1106643984 15294688 9114752
05:35:34 36937 8192000000 10017667600 10407079936 1084738704 11.693 100 59 119 1 1127414832 29409936 13266192
05:37:11 10545 8192000000 10010508736 10415505408 1090346720 11.746 100 38 76 0 1118573648 19955888 8271040
05:38:26 9115 8192000000 10016331440 10420236288 1097741008 11.816 100 31 62 0 1128153568 23305312 7107248
05:39:38 11981 8192000000 10025742480 10440962048 1087715968 11.721 99 22 44 1 1119126608 26831952 4578688
05:40:53 49269 8192000000 9984618448 10390188032 1024482720 11.115 94 37 74 1 1048168368 15836272 7849376
05:42:46 4111 8192000000 9940890400 10323673088 1033673008 11.204 100 25 48 4 1058500400 19305424 5521968
05:43:54 10565 8192000000 9936552080 10327625728 1009630992 10.972 98 30 60 2 1046999616 30596944 6771680
05:45:08 62328 8192000000 9929105904 10314559488 983546416 10.719 96 28 56 2 1008679104 18364720 6767968
05:47:22 5971 8192000000 9957883696 10346176512 1042140672 11.285 100 49 97 1 1071033552 17720176 11172704
05:48:29 12211 8192000000 9971698352 10375217152 1043397888 11.297 99 37 73 1 1063342704 11898464 8046352
05:49:48 10045 8192000000 9978846816 10390814720 1064532960 11.500 100 32 63 1 1098718880 26799472 7386448
05:51:01 62476 8192000000 10021212784 10424356864 1087881296 11.723 100 40 80 1 1119462512 23225600 8355616
05:53:44 8126 8192000000 10089559104 10493898752 1111435648 11.946 100 42 84 1 1198430384 78310896 8683840
05:54:55 14084 8192000000 10104676688 10514972672 1123729856 12.062 100 53 105 2 1221354976 86595296 11029824
05:56:19 9976 8192000000 10144208896 10554544128 1136317920 12.181 100 47 94 0 1250343376 103867312 10158144
05:57:39 1951 8192000000 10176182720 10574962688 1148023792 12.291 100 68 135 3 1271044656 109271760 13749104
05:58:45 8767 8192000000 10210236688 10641399808 1165490608 12.455 100 52 104 1 1326482384 149116208 11875568
06:00:03 12285 8192000000 10240811360 10648539136 1173005728 12.525 100 60 118 0 1344110384 157996816 13107840
06:01:20 5551 8192000000 10275708048 10697641984 1179993072 12.590 100 69 138 0 1374856144 179761024 15102048
06:02:32 2958 8192000000 10330904976 10746327040 1193221584 12.713 100 78 155 0 1434680160 223654160 17804416
06:03:41 1436 8192000000 10347092672 10769723392 1186176224 12.648 100 80 157 1 1445782960 241992368 17614368
06:04:48 2372 8192000000 10364222400 10784079872 1178687200 12.578 100 84 167 1 1463940368 266387040 18866128
06:05:55 3184 8192000000 10320553216 10728136704 1143410848 12.248 98 74 148 1 1405856768 245974640 16471280
06:07:03 1247 8192000000 10246238560 10641629184 1128215184 12.105 99 85 166 1 1342195856 195397632 18583040
06:08:08 34780 8192000000 10260584576 10693558272 1138978880 12.206 99 32 64 2 1352219024 205285216 7954928
06:09:45 9603 8192000000 10294114000 10725969920 1154679392 12.353 100 39 77 0 1405690544 241312576 9698576
06:10:59 5842 8192000000 10360597648 10783670272 1162465712 12.426 100 60 120 1 1460407984 282829040 15113232
06:12:10 4528 8192000000 10378560048 10811064320 1151497872 12.324 100 48 95 1 1475115792 311691328 11926592
06:13:27 14985 8192000000 10350981440 10813464576 1160010960 12.403 100 35 70 1 1453838352 284248816 9578576
06:14:54 26869 8192000000 10305869184 10755993600 1106145408 11.896 97 29 57 0 1398523776 285225968 7152400
06:16:22 17574 8192000000 10307385792 10752118784 1093952864 11.780 99 20 40 1 1404499424 305171712 5374848
06:17:56 65236 8192000000 10035689584 10458058752 1077066576 11.620 98 19 38 1 1224063744 142185104 4812064
06:20:23 47242 8192000000 10025924544 10458443776 1068880288 11.541 98 23 47 1 1103264368 29994048 4390032
06:22:28 42756 8192000000 10012908608 10468065280 1098885952 11.827 100 26 52 1 1134418464 28494496 7038016
06:24:18 134269 8192000000 10086575280 10526810112 1119472976 12.022 99 23 45 0 1179148624 54804848 4870800
06:27:34 159928 8192000000 10117222848 10560086016 1123100464 12.056 99 21 42 1 1207700688 80118816 4481408
06:31:47 26513 8192000000 10077152288 10535452672 1128990256 12.112 99 16 31 3 1188693248 56802976 2900016
06:33:15 46943 8192000000 10108532528 10548535296 1128126576 12.104 99 45 90 2 1211107712 73233360 9747776
06:35:06 64992 8192000000 10146524480 10629529600 1145218448 12.265 100 27 54 0 1264011152 111899200 6893504
06:38:02 142652 8192000000 10044825840 10499395584 1096252096 11.802 96 10 20 0 1187651584 89160592 2238896
06:41:26 72086 8192000000 10110402160 10556813312 1127617552 12.099 98 24 48 1 1179730272 46604688 5508032
06:44:20 88502 8192000000 10078857584 10552565760 1136685312 12.184 99 4 8 0 1179068992 41758400 625280
06:46:59 55924 8192000000 10109986352 10546450432 1135539520 12.174 99 29 58 1 1185347360 43741184 6066656
06:48:56 98589 8192000000 10052296864 10511265792 1121653248 12.043 98 17 33 2 1151955024 26811664 3490112
06:51:35 41958 8192000000 10084329024 10515091456 1121761200 12.044 99 32 62 1 1168359616 39941472 6656944
06:53:27 49886 8192000000 10057357536 10533662720 1100047120 11.838 97 11 23 0 1160881792 58634032 2200640
06:55:17 63324 8192000000 10103089872 10551902208 1116733040 11.996 99 19 37 2 1206070448 85079392 4258016
06:57:21 72830 8192000000 10105459568 10584383488 1124188240 12.067 99 14 28 2 1231870128 104524240 3157648
06:59:38 63642 8192000000 10069821024 10553679872 1079692704 11.645 95 13 26 0 1136899904 54783424 2423776
07:01:46 38437 8192000000 10106595984 10544877568 1128488912 12.107 100 59 113 0 1170224944 30605280 11130752
07:03:25 90233 8192000000 10041566112 10480033792 1038283008 11.248 92 18 36 0 1101387808 59544176 3560624
07:05:57 46254 8192000000 10110017424 10578862080 1111473248 11.946 99 31 62 0 1193120704 75463328 6184128
07:07:44 76527 8192000000 10046106448 10539610112 1073603536 11.586 96 16 32 0 1120949424 44254400 3091488
07:10:06 52236 8192000000 10055578016 10485731328 1084200720 11.687 97 22 44 1 1119205344 30808608 4196016
07:11:58 90580 8192000000 10086486592 10518921216 1101604432 11.853 98 44 88 3 1153301456 41699072 9997952
07:14:29 48614 8192000000 10078215616 10554503168 1110686912 11.939 99 17 34 0 1162263328 48067440 3508976
07:16:25 49761 8192000000 10113129696 10559963136 1132739040 12.147 100 16 32 1 1211071232 74820032 3512160
07:18:19 76286 8192000000 10070381472 10601394176 1123120896 12.056 100 14 28 0 1247104608 120557296 3426416
07:20:39 38494 8192000000 10061435008 10499964928 1126734016 12.091 100 22 44 1 1163753984 32653504 4366464
07:22:18 58163 8192000000 10088916544 10561052672 1105576336 11.891 98 26 52 1 1170895248 59612336 5706576
07:24:32 59815 8192000000 10117817984 10572152832 1094698736 11.787 97 23 46 1 1184531792 84981440 4851616
07:26:34 105184 8192000000 10073926368 10503122944 1118922624 12.017 98 22 44 0 1167418736 44238768 4257344
07:29:19 49116 8192000000 10144965312 10592411648 1139268896 12.209 100 28 56 0 1209998400 64830224 5899280
07:31:18 142998 8192000000 10111053632 10548490240 1081033856 11.657 95 15 30 0 1117162992 33177488 2951648
07:34:41 48529 8192000000 10046374048 10520829952 1103265328 11.869 98 15 31 0 1148345472 42163872 2916272
07:36:30 45294 8192000000 10040370304 10495741952 1126734288 12.091 100 7 15 0 1156925760 28564272 1627200
07:38:31 62207 8192000000 10080101712 10526162944 1116501200 11.994 98 20 40 0 1153750768 31992736 5256832
07:41:40 51436 8192000000 10077060464 10525286400 1131564688 12.136 100 17 32 2 1176872768 41487264 3820816
07:43:34 46483 8192000000 10130085248 10576400384 1121646768 12.043 98 27 53 2 1187444320 59622976 6174576
07:45:48 78328 8192000000 10107942576 10619682816 1142232944 12.237 100 29 56 2 1235762768 87244544 6285280
07:48:41 43595 8192000000 10143202720 10576924672 1133510368 12.154 99 47 94 0 1210443616 67064400 9868848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-08-2021 07:51:42 to 26-08-2021 07:51:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 79 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 26-08-2021 07:51:58 to 26-08-2021 07:52:00.

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          230976
MON$OLDEST_ACTIVE               233465
MON$OLDEST_SNAPSHOT             233465
MON$NEXT_TRANSACTION            233465
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                       2036320
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2573 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2573 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2573 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2573 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Aug 26 07:50:32 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Aug 26 07:50:41 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Aug 26 07:50:42 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Thu Aug 26 07:50:43 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Thu Aug 26 07:50:43 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:25: 
--- end of diff output ---

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

26.08.21 07:52:01. 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
2021-08-26 07:52:04 No severe PSQL-related problems occured
26.08.21 07:52:05. End of report.