Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.05.2022 10:37
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 16374484 kB
MemAvailable 31385020 kB
Buffers 0 kB
Cached 15297392 kB
SwapCached 2592 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-05-19 10:37:15
DTS_END                         2022-05-19 10:37:15.9640
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/59106
TRN_ID                          218502
ATT_ID                          614
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: 8959.24 27807 1075109
customer order: creation 161.52 473 19382
customer order: refuse 13.97 372 1676
order to supplier: creation 43.08 1300 5169
order to supplier: removal 3.43 153615 411
invoice (draft): creation 43.89 3155 5267
invoice (draft): removal 50.28 882 6034
invoice accept: apply 40.54 27518 4865
invoice accept: cancel 45.62 1205 5474
customer reserve: creation 3803.29 263 456395
customer reserve: removal 4470.43 94 536452
realization accept: apply 78.20 240 9384
realization accept: cancel 80.83 157 9700
payment from customer: creation 57.28 154 6873
payment from customer: removal 5.49 123 659
payment to supplier: creation 46.22 182 5546
payment to supplier: removal 3.33 136 399
service: total inventory turnovers 4.07 3580 488
service: total monetary turnovers 6.86 409 823
service: aggregate perf. data 0.90 13731 108
service: refresh index statistics 0.03 348542 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-05-2022 10:38:01 to 19-05-2022 10:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7931
TEST_TIME 2 7434
TEST_TIME 3 6563
TEST_TIME 4 6408
TEST_TIME 5 6081
TEST_TIME 6 6026
TEST_TIME 7 6462
TEST_TIME 8 6629
TEST_TIME 9 6877
TEST_TIME 10 6837
TEST_TIME 11 6870
TEST_TIME 12 7171
TEST_TIME 13 7000
TEST_TIME 14 7391
TEST_TIME 15 7513
TEST_TIME 16 7499
TEST_TIME 17 8032
TEST_TIME 18 8064
TEST_TIME 19 8016
TEST_TIME 20 8328
TEST_TIME 21 8604
TEST_TIME 22 8496
TEST_TIME 23 8767
TEST_TIME 24 9117
TEST_TIME 25 9121
TEST_TIME 26 9462
TEST_TIME 27 9419
TEST_TIME 28 9866
TEST_TIME 29 9978
TEST_TIME 30 10538
TEST_TIME 31 10812
TEST_TIME 32 10916
TEST_TIME 33 10684
TEST_TIME 34 10538
TEST_TIME 35 10307
TEST_TIME 36 10175
TEST_TIME 37 10221
TEST_TIME 38 10057
TEST_TIME 39 9988
TEST_TIME 40 9898
TEST_TIME 41 9795
TEST_TIME 42 9665
TEST_TIME 43 9591
TEST_TIME 44 9468
TEST_TIME 45 9383
TEST_TIME 46 9255
TEST_TIME 47 9290
TEST_TIME 48 9303
TEST_TIME 49 9249
TEST_TIME 50 9183
TEST_TIME 51 9269
TEST_TIME 52 9197
TEST_TIME 53 9141
TEST_TIME 54 9190
TEST_TIME 55 9262
TEST_TIME 56 9225
TEST_TIME 57 9254
TEST_TIME 58 9300
TEST_TIME 59 9336
TEST_TIME 60 9323
TEST_TIME 61 9277
TEST_TIME 62 9264
TEST_TIME 63 9204
TEST_TIME 64 9120
TEST_TIME 65 9175
TEST_TIME 66 9115
TEST_TIME 67 9060
TEST_TIME 68 8998
TEST_TIME 69 9001
TEST_TIME 70 9024
TEST_TIME 71 9000
TEST_TIME 72 8974
TEST_TIME 73 9006
TEST_TIME 74 9041
TEST_TIME 75 8979
TEST_TIME 76 8979
TEST_TIME 77 9019
TEST_TIME 78 9128
TEST_TIME 79 9142
TEST_TIME 80 9141
TEST_TIME 81 9142
TEST_TIME 82 9227
TEST_TIME 83 9146
TEST_TIME 84 9085
TEST_TIME 85 9073
TEST_TIME 86 9031
TEST_TIME 87 8965
TEST_TIME 88 8968
TEST_TIME 89 8932
TEST_TIME 90 8982
TEST_TIME 91 8945
TEST_TIME 92 8922
TEST_TIME 93 8943
TEST_TIME 94 8974
TEST_TIME 95 8954
TEST_TIME 96 8971
TEST_TIME 97 8963
TEST_TIME 98 9026
TEST_TIME 99 9015
TEST_TIME 100 9019
TEST_TIME 101 9016
TEST_TIME 102 8996
TEST_TIME 103 8961
TEST_TIME 104 8934
TEST_TIME 105 8872
TEST_TIME 106 8840
TEST_TIME 107 8845
TEST_TIME 108 8814
TEST_TIME 109 8793
TEST_TIME 110 8795
TEST_TIME 111 8770
TEST_TIME 112 8750
TEST_TIME 113 8715
TEST_TIME 114 8692
TEST_TIME 115 8716
TEST_TIME 116 8703
TEST_TIME 117 8747
TEST_TIME 118 8783
TEST_TIME 119 8890
TEST_TIME 120 8985

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-05-2022 10:38:05 to 19-05-2022 10:38:08.

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 1494199 1494199 0 0.00 0 18759 13 0 0
doc_list_biud 1494187 1494187 0 0.00 0 74796 39 0 0
sp_add_invoice_to_stock 4992 4865 127 2.54 80 207265 27518 0 127
sp_cancel_adding_invoice 5474 5474 0 0.00 6 97409 1205 0 0
sp_cancel_client_order 1676 1676 0 0.00 6 14503 372 0 0
sp_cancel_customer_reserve 536452 536452 0 0.00 1 52863 95 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 4 5072 123 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 10816 137 0 0
sp_cancel_supplier_invoice 6044 6034 10 0.16 3 74805 883 0 10
sp_cancel_supplier_order 411 411 0 0.00 6 1207464 153615 0 0
sp_cancel_write_off 9700 9700 0 0.00 2 7388 157 0 0
sp_client_order 19382 19382 0 0.00 6 45977 474 0 0
sp_customer_reserve 456395 456395 0 0.00 0 91604 264 0 0
sp_fill_shopping_cart 29805 29805 0 0.00 2 20045 76 0 0
sp_fill_shopping_cart_clo_res 456358 456358 0 0.00 0 19158 21 0 0
sp_get_clo_for_invoice 4822 4822 0 0.00 0 20294 281 0 0
sp_kill_cost_storno 16232 16232 0 0.00 0 8256 65 0 0
sp_kill_qstorno_ret_qs2qd 1089049 1089049 0 0.00 0 74790 42 0 0
sp_kill_qty_storno 573951 573951 0 0.00 0 74796 96 0 0
sp_lock_selected_doc 19753 19753 0 0.00 0 7232 7 0 0
sp_make_cost_storno 26625 26625 0 0.00 0 18745 69 0 0
sp_multiply_rows_for_pdistr 26623 26623 0 0.00 0 10830 35 0 0
sp_multiply_rows_for_qdistr 19382 19382 0 0.00 0 14651 189 0 0
sp_pay_from_customer 6873 6873 0 0.00 5 14259 155 0 0
sp_pay_to_supplier 5546 5546 0 0.00 6 45298 182 0 0
sp_payment_common 12419 12419 0 0.00 2 11471 38 0 0
sp_qd_handle_on_cancel_clo 1676 1676 0 0.00 0 14438 303 0 0
sp_qd_handle_on_invoice_upd_sts 10295 10295 0 0.00 0 37600 473 0 0
sp_qd_handle_on_reserve_upd_sts 9383 9383 0 0.00 0 15053 60 0 0
sp_reserve_write_off 9384 9384 0 0.00 8 15225 240 0 0
sp_supplier_invoice 5267 5267 0 0.00 13 95228 3155 0 0
sp_supplier_order 5169 5169 0 0.00 7 35008 1301 0 0
srv_aggregate_perf_data 108 108 0 0.00 2897 153281 13731 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 921 106055 37196 0 0
srv_find_qd_qs_mism 1027349 1027349 0 0.00 0 13275 11 0 0
srv_make_invnt_saldo 488 488 0 0.00 54 65728 3580 0 0
srv_make_money_saldo 823 823 0 0.00 1 14816 410 0 0
srv_recalc_idx_stat 4 4 0 0.00 243654 507421 348543 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4868 4868 0 0.00 1 17995 814 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 1 6459 452 0 0
t$perf-norm:sp_cancel_client_order 1676 1676 0 0.00 0 2033 28 0 0
t$perf-norm:sp_cancel_customer_reserve 651 651 0 0.00 0 2841 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 436 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 1472 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1375 25 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 91130 5792 0 0
t$perf-norm:sp_cancel_write_off 947 947 0 0.00 0 4216 23 0 0
t$perf-norm:sp_client_order 19383 19383 0 0.00 0 6339 32 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 984 12 0 0
t$perf-norm:sp_pay_from_customer 6873 6873 0 0.00 0 21545 24 0 0
t$perf-norm:sp_pay_to_supplier 5546 5546 0 0.00 0 9207 20 0 0
t$perf-norm:sp_reserve_write_off 9384 9384 0 0.00 0 17648 25 0 0
t$perf-norm:sp_supplier_invoice 5268 5268 0 0.00 0 5716 32 0 0
t$perf-norm:sp_supplier_order 5169 5169 0 0.00 0 11178 31 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 431 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 566 16 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 1983 18 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 572 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 299 80 0 0
v_add_invoice_to_stock 9884 9630 254 2.56 0 2821 19 0 254
v_all_customers 35725 35725 0 0.00 0 107 3 0 0
v_all_suppliers 29272 29272 0 0.00 0 99 4 0 0
v_all_wares 38758 38758 0 0.00 0 112 3 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 2704 14 0 0
v_cancel_client_order 3352 3352 0 0.00 0 1757 8 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 3347 12 0 0
v_cancel_customer_reserve 1302 1302 0 0.00 0 2537 10 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1851 13 0 0
v_cancel_supplier_invoice 816 796 20 2.45 0 439 18 0 20
v_cancel_supplier_order 822 822 0 0.00 0 776 11 0 0
v_cancel_write_off 1894 1894 0 0.00 0 2860 21 0 0
v_max_id_clo_ord 5164 5164 0 0.00 0 665 4 0 0
v_max_id_clo_res 962 962 0 0.00 0 2983 18 0 0
v_max_id_ord_sup 5260 5260 0 0.00 0 3004 6 0 0
v_max_non_paid_invoice 1334 1334 0 0.00 0 57 7 0 0
v_max_non_paid_realizn 3556 3556 0 0.00 0 958 6 0 0
v_min_id_clo_ord 5164 5164 0 0.00 0 5601 8 0 0
v_min_id_clo_res 962 962 0 0.00 0 6481 29 0 0
v_min_id_ord_sup 5260 5260 0 0.00 0 4124 7 0 0
v_min_non_paid_invoice 5546 5546 0 0.00 0 5999 14 0 0
v_min_non_paid_realizn 6872 6872 0 0.00 0 11466 15 0 0
v_our_firm 9664 9664 0 0.00 0 47 4 0 0
v_reserve_write_off 18766 18766 0 0.00 0 2641 10 0 0
x_lock_depdocs_on_canc_invoice 11110 11110 0 0.00 0 19841 124 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 14362 368 0 0
x_make_qstorno_1000_1200 5169 5169 0 0.00 3 34992 1152 0 0
x_make_qstorno_1200_2000 5267 5267 0 0.00 7 79864 2935 0 0
x_make_qstorno_2100_3300 456394 456394 0 0.00 0 91602 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-05-2022 10:38:09 to 19-05-2022 10:38:13.

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:19 3 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 28281 8192000000 9731834512 10034819072 808267744 8.980 100 85 170 0 836878832 7998640 20612448
08:09:52 27381 8192000000 9770980112 10083201024 840704640 9.307 100 89 178 0 875719920 13615408 21399872
08:11:28 14916 8192000000 9819315216 10141364224 871076176 9.611 100 89 178 0 922832112 31239072 20516864
08:12:45 6214 8192000000 9870410352 10200625152 904484080 9.943 100 84 168 0 972964896 49907968 18572848
08:13:57 2735 8192000000 9905781680 10246963200 950828912 10.399 100 88 173 0 1026717024 57960080 17928032
08:15:04 2310 8192000000 9955073040 10305617920 989859552 10.780 100 88 172 0 1068496304 61682304 16954448
08:16:08 1685 8192000000 10004350352 10366009344 1019794544 11.070 100 96 183 0 1129888576 91006304 19087728
08:17:11 1232 8192000000 9986842112 10359418880 1041737872 11.281 100 92 180 0 1110767328 50680896 18348560
08:18:14 1167 8192000000 10030228400 10407231488 1053886384 11.398 100 92 184 0 1153105056 80484256 18734416
08:19:16 1468 8192000000 10036300864 10413867008 1066234960 11.516 100 94 182 0 1155596064 70247088 19114016
08:20:18 1014 8192000000 10045663456 10422263808 1065740848 11.511 100 94 184 1 1163423200 79367312 18315040
08:21:20 1110 8192000000 10054956736 10441175040 1071736448 11.569 100 83 160 1 1170257264 82717888 15802928
08:22:22 1095 8192000000 10023056832 10410721280 1071670736 11.568 100 94 173 2 1143943024 54671408 17600880
08:23:24 1300 8192000000 10034830288 10416586752 1082723520 11.673 100 94 177 1 1153977792 54004880 17249392
08:24:25 1356 8192000000 10070266240 10459344896 1083102512 11.677 100 98 188 0 1194558176 91596832 19858832
08:25:28 1253 8192000000 10060848640 10450120704 1085588944 11.701 100 94 182 0 1183621344 77973008 20059392
08:26:30 1073 8192000000 10038697696 10430509056 1091069632 11.753 100 80 158 1 1163250688 55244560 16936496
08:27:33 1515 8192000000 10041947616 10429530112 1087705152 11.721 100 96 184 7 1161012288 53970432 19336704
08:28:35 1031 8192000000 10046620192 10430169088 1083244128 11.678 100 93 182 0 1167761584 65419488 19097968
08:29:38 1316 8192000000 10032410800 10412179456 1063636048 11.491 100 94 178 2 1157665424 75195328 18834048
08:30:40 1402 8192000000 10053938704 10446573568 1072828560 11.579 100 82 158 1 1178611840 90029136 15754144
08:31:42 2572 8192000000 10034292192 10412081152 1059191440 11.449 100 93 176 0 1153760192 76800192 17768560
08:32:46 7863 8192000000 9989549056 10369347584 1046916256 11.331 99 71 132 2 1113168144 53542224 12709664
08:33:54 3814 8192000000 10013030880 10393989120 1051441776 11.375 100 78 154 1 1129007136 62415664 15149696
08:34:59 894 8192000000 9992044720 10366775296 1039948016 11.264 100 90 170 0 1115511456 57782464 17780976
08:36:01 938 8192000000 9989665120 10362679296 1027253520 11.142 100 88 160 0 1111154960 67256320 16645120
08:37:03 955 8192000000 9995167728 10371739648 1034592416 11.213 100 89 160 0 1117573840 65086016 17895408
08:38:13 6605 8192000000 10015726224 10387165184 1042423872 11.288 99 73 135 0 1129618352 74136384 13058096
08:39:36 12667 8192000000 10031651696 10417512448 1067912208 11.532 100 68 124 1 1156538048 75672352 12953488
08:41:09 961 8192000000 10076582608 10456494080 1088483168 11.728 100 94 174 1 1197720272 88963520 20273584
08:42:13 3951 8192000000 10108127648 10495873024 1098014592 11.819 100 72 133 1 1227340480 116165744 13160144
08:43:21 8785 8192000000 10168412272 10576715776 1118352624 12.011 100 63 125 2 1269307056 136935776 14018656
08:44:35 3500 8192000000 10168702000 10565025792 1131207104 12.133 100 70 138 0 1281297376 134736256 15354016
08:45:41 1694 8192000000 10196633904 10597580800 1139182416 12.208 100 95 183 2 1305551936 146999136 19370384
08:46:45 1507 8192000000 10220885664 10614161408 1147476704 12.286 100 84 163 1 1334534512 168884288 18173520
08:47:50 3892 8192000000 10252839776 10656358400 1161465984 12.417 100 71 140 0 1367865936 190534016 15865936
08:48:57 1336 8192000000 10296982656 10710720512 1172702496 12.522 100 91 177 0 1405088064 213091296 19294272
08:50:01 918 8192000000 10302315744 10703941632 1168412544 12.482 100 94 180 2 1413818656 225718016 19688096
08:51:07 2233 8192000000 10328953616 10762145792 1172064352 12.516 100 95 186 1 1441900416 247628944 22207120
08:52:14 2309 8192000000 10354452288 10775035904 1179339824 12.584 100 87 169 0 1462173888 263801728 19032336
08:53:23 1215 8192000000 10347582672 10781396992 1181260080 12.602 100 89 175 2 1453613856 250979024 21374752
08:54:30 1159 8192000000 10354154384 10775592960 1174855536 12.542 100 98 194 1 1458891776 261107456 22928784
08:55:34 959 8192000000 10373742768 10779099136 1184265200 12.630 100 99 191 1 1479175728 272521328 22389200
08:56:38 1341 8192000000 10390836112 10822377472 1189918720 12.683 100 85 163 0 1490837840 281120144 19798976
08:57:40 1139 8192000000 10457665568 10884763648 1192781328 12.709 100 95 189 0 1565531696 350532896 22217472
08:58:43 16631 8192000000 10402893984 10845585408 1167635344 12.475 99 35 69 0 1488119376 311216336 9267696
09:00:05 6174 8192000000 10420434544 10862866432 1164254752 12.443 99 58 116 0 1522431280 343301472 14875056
09:01:19 4044 8192000000 10379186800 10815078400 1171402576 12.510 100 51 100 2 1490245920 305668368 13174976
09:02:37 14601 8192000000 10388999984 10850045952 1168430960 12.482 100 37 74 0 1508192976 329677104 10084912
09:04:02 5459 8192000000 10362566960 10814337024 1171650704 12.512 100 48 90 1 1469199328 286853984 10694640
09:05:09 12542 8192000000 10304149488 10797068288 1149143968 12.301 99 29 58 1 1424367600 268267456 6956176
09:06:42 49256 8192000000 10159874688 10708041728 1110662736 11.939 97 13 26 0 1343923872 230007728 3253408
09:08:33 52491 8192000000 10070138720 10622414848 1105013552 11.885 97 9 18 1 1177561216 70752400 1795264
09:11:10 37892 8192000000 10135459952 10587820032 1112861808 11.960 99 43 84 2 1213193232 91573104 8758320
09:13:32 87642 8192000000 10072697696 10589564928 1128610000 12.108 100 16 32 0 1207875488 75549104 3716384
09:16:23 67078 8192000000 10060829984 10569785344 1110166784 11.934 98 13 26 1 1164410752 51724224 2519744
09:18:31 49098 8192000000 10064021552 10513752064 1112825968 11.959 98 15 30 0 1143964400 28433552 2704880
09:20:21 80844 8192000000 10167142176 10617614336 1136273024 12.180 99 27 52 1 1220820752 78805808 5741920
09:22:43 100782 8192000000 10142017216 10612211712 1132674752 12.147 99 15 30 0 1242456800 106564608 3217440
09:25:25 57844 8192000000 10070359312 10538512384 1102412000 11.861 97 19 38 1 1155763056 49526512 3824544
09:27:25 106054 8192000000 10086554736 10531749888 1104055952 11.876 97 15 30 1 1166269264 58101904 4111408
09:30:12 27082 8192000000 10125076112 10569363456 1142224224 12.236 100 44 84 2 1215124464 64710944 8189296
09:32:40 48104 8192000000 10099462192 10550546432 1132752368 12.147 99 13 26 0 1217213712 81573984 2887360
09:34:28 41370 8192000000 10130683792 10567073792 1129355120 12.115 98 30 55 1 1203982768 69002384 5625264
09:36:12 69729 8192000000 10109814256 10582003712 1139066848 12.207 99 15 29 0 1203733632 61541536 3125248
09:38:23 27630 8192000000 10173487184 10605125632 1135503616 12.173 99 44 88 1 1238067504 93723024 8840864
09:39:55 104063 8192000000 10142120368 10595606528 1148561840 12.296 100 29 58 0 1250354896 95605104 6187952
09:43:00 71784 8192000000 10095687200 10560708608 1125740800 12.081 98 11 22 0 1196766496 68856208 2169488
09:45:18 61581 8192000000 10099345184 10562695168 1111662544 11.948 98 26 44 0 1183976752 68129184 4185024
09:47:22 88253 8192000000 10083168480 10523795456 1085524560 11.700 95 20 38 1 1130313792 40884160 3905072
09:49:52 32091 8192000000 10121154144 10567733248 1127301440 12.096 99 30 60 2 1217996000 84314560 6380000
09:51:24 43072 8192000000 10085843440 10551930880 1113549168 11.966 97 23 46 3 1183600608 64785664 5265776
09:53:08 26523 8192000000 10153320608 10588602368 1137312528 12.190 99 43 86 1 1258377600 111716480 9348592
09:54:35 66307 8192000000 10091437696 10592120832 1120167184 12.029 98 15 30 0 1218274240 94712592 3394464
09:56:44 29056 8192000000 10121505664 10575237120 1138869216 12.205 100 48 94 2 1214166128 65563840 9733072
09:58:15 32077 8192000000 10195424672 10644971520 1156698736 12.372 100 49 98 0 1277621216 111381488 9540992
09:59:57 17093 8192000000 10106475696 10529259520 1150834176 12.317 100 67 135 3 1201572960 36885312 13853472
10:01:18 57468 8192000000 10135542336 10574352384 1152980160 12.337 100 56 112 1 1239434896 74460592 11994144
10:03:22 64894 8192000000 10140135728 10632400896 1158705648 12.391 100 9 18 1 1243630096 82940096 1984352
10:05:27 59864 8192000000 10141465520 10642731008 1151504896 12.324 99 15 30 0 1231913072 76784768 3623408
10:07:31 76262 8192000000 10073038400 10523054080 1086361712 11.708 95 12 24 1 1123842576 34752512 2728352
10:09:55 84568 8192000000 10077011088 10550349824 1122180816 12.048 98 29 58 1 1202869216 74241152 6447248
10:12:20 21176 8192000000 10100252128 10533863424 1130095760 12.122 100 49 98 1 1181986064 41685344 10204960
10:13:54 39596 8192000000 10097557136 10547089408 1100233200 11.840 97 36 67 1 1148951776 41730560 6988016
10:15:36 68201 8192000000 10083459360 10526273536 1103897504 11.875 97 35 68 0 1179424672 68694784 6832384
10:17:46 53033 8192000000 10120476608 10566205440 1104240512 11.878 97 42 84 0 1209555152 96999056 8315584
10:19:44 81277 8192000000 10063154160 10552721408 1102667264 11.863 98 30 60 0 1168884528 59957072 6260192
10:22:06 76764 8192000000 10073797920 10550468608 1117160976 12.000 99 10 20 0 1171041200 51667616 2212608
10:24:25 53993 8192000000 10063972480 10543915008 1119365168 12.021 99 21 41 2 1162415712 39132992 3917552
10:26:31 27453 8192000000 10090531840 10541563904 1113318048 11.964 98 30 60 2 1172138944 52689152 6131744
10:28:12 28019 8192000000 10121969328 10557218816 1111963712 11.951 98 34 68 3 1192348464 73369328 7015424
10:29:41 33838 8192000000 10178140208 10618523648 1131185312 12.133 99 36 72 1 1270518816 131141232 8192272
10:31:15 20585 8192000000 10261757744 10695921664 1153149280 12.339 100 51 101 0 1355908192 191362160 11396752
10:32:44 27016 8192000000 10244804512 10684825600 1136695040 12.184 99 40 78 3 1365072240 219386128 8991072
10:34:19 52345 8192000000 10242866672 10723323904 1145699008 12.269 100 27 52 4 1373371760 221057216 6615536
10:36:17 49557 8192000000 10130054560 10651078656 1126885696 12.092 99 13 26 0 1295018512 165003184 3129632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-05-2022 10:38:15 to 19-05-2022 10:38:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-05-2022 10:38:31 to 19-05-2022 10:38:33.

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          218457
MON$OLDEST_ACTIVE               218671
MON$OLDEST_SNAPSHOT             218671
MON$NEXT_TRANSACTION            218671
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                       2045216
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2766 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2766 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2766 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2766 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Thu May 19 10:37:19 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Thu May 19 10:37:27 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Thu May 19 10:37:27 2022
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
--- end of diff output ---

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

19.05.22 10:38:34. 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-05-19 10:38:37 No severe PSQL-related problems occured
19.05.22 10:38:38. End of report.