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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15633436 kB
MemAvailable 30961420 kB
Buffers 20 kB
Cached 15992992 kB
SwapCached 2512 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-07-29 04:37:18
DTS_END                         2022-07-29 04:37:18.9740
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/40420
TRN_ID                          227350
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: 8821.07 26862 1058528
customer order: creation 176.93 443 21232
customer order: refuse 15.12 426 1814
order to supplier: creation 46.61 1270 5593
order to supplier: removal 3.14 150148 377
invoice (draft): creation 47.70 2936 5724
invoice (draft): removal 47.93 960 5751
invoice accept: apply 42.85 24213 5142
invoice accept: cancel 43.13 1073 5176
customer reserve: creation 3922.43 233 470691
customer reserve: removal 4177.05 87 501246
realization accept: apply 87.81 262 10537
realization accept: cancel 77.63 151 9316
payment from customer: creation 60.29 155 7235
payment from customer: removal 5.93 120 712
payment to supplier: creation 50.38 167 6046
payment to supplier: removal 3.49 95 419
service: total inventory turnovers 4.27 3588 512
service: total monetary turnovers 7.42 330 890
service: aggregate perf. data 0.93 15271 111
service: refresh index statistics 0.03 335307 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-07-2022 04:38:26 to 29-07-2022 04:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5682
TEST_TIME 2 5847
TEST_TIME 3 6003
TEST_TIME 4 6087
TEST_TIME 5 5950
TEST_TIME 6 5757
TEST_TIME 7 6162
TEST_TIME 8 6681
TEST_TIME 9 6912
TEST_TIME 10 7070
TEST_TIME 11 7064
TEST_TIME 12 7115
TEST_TIME 13 7115
TEST_TIME 14 7305
TEST_TIME 15 7504
TEST_TIME 16 7465
TEST_TIME 17 7698
TEST_TIME 18 7777
TEST_TIME 19 8222
TEST_TIME 20 8389
TEST_TIME 21 8519
TEST_TIME 22 8698
TEST_TIME 23 8874
TEST_TIME 24 8886
TEST_TIME 25 8920
TEST_TIME 26 8907
TEST_TIME 27 9252
TEST_TIME 28 9756
TEST_TIME 29 9912
TEST_TIME 30 10291
TEST_TIME 31 10268
TEST_TIME 32 10473
TEST_TIME 33 10618
TEST_TIME 34 10538
TEST_TIME 35 10443
TEST_TIME 36 10383
TEST_TIME 37 10175
TEST_TIME 38 10189
TEST_TIME 39 10243
TEST_TIME 40 10236
TEST_TIME 41 10150
TEST_TIME 42 10050
TEST_TIME 43 9939
TEST_TIME 44 9963
TEST_TIME 45 9896
TEST_TIME 46 9807
TEST_TIME 47 9750
TEST_TIME 48 9724
TEST_TIME 49 9786
TEST_TIME 50 9779
TEST_TIME 51 9764
TEST_TIME 52 9817
TEST_TIME 53 9906
TEST_TIME 54 9725
TEST_TIME 55 9635
TEST_TIME 56 9525
TEST_TIME 57 9403
TEST_TIME 58 9416
TEST_TIME 59 9351
TEST_TIME 60 9294
TEST_TIME 61 9216
TEST_TIME 62 9271
TEST_TIME 63 9242
TEST_TIME 64 9143
TEST_TIME 65 9162
TEST_TIME 66 9244
TEST_TIME 67 9261
TEST_TIME 68 9255
TEST_TIME 69 9193
TEST_TIME 70 9179
TEST_TIME 71 9249
TEST_TIME 72 9180
TEST_TIME 73 9198
TEST_TIME 74 9161
TEST_TIME 75 9258
TEST_TIME 76 9225
TEST_TIME 77 9336
TEST_TIME 78 9307
TEST_TIME 79 9277
TEST_TIME 80 9178
TEST_TIME 81 9166
TEST_TIME 82 9121
TEST_TIME 83 9084
TEST_TIME 84 9060
TEST_TIME 85 9037
TEST_TIME 86 8992
TEST_TIME 87 8977
TEST_TIME 88 8945
TEST_TIME 89 8894
TEST_TIME 90 8914
TEST_TIME 91 8965
TEST_TIME 92 8950
TEST_TIME 93 8978
TEST_TIME 94 8967
TEST_TIME 95 8982
TEST_TIME 96 8966
TEST_TIME 97 9061
TEST_TIME 98 9068
TEST_TIME 99 9071
TEST_TIME 100 9115
TEST_TIME 101 9103
TEST_TIME 102 9161
TEST_TIME 103 9134
TEST_TIME 104 9103
TEST_TIME 105 9052
TEST_TIME 106 9002
TEST_TIME 107 8981
TEST_TIME 108 8958
TEST_TIME 109 8960
TEST_TIME 110 8947
TEST_TIME 111 8917
TEST_TIME 112 8876
TEST_TIME 113 8872
TEST_TIME 114 8872
TEST_TIME 115 8863
TEST_TIME 116 8876
TEST_TIME 117 8851
TEST_TIME 118 8828
TEST_TIME 119 8867
TEST_TIME 120 8844

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-07-2022 04:38:29 to 29-07-2022 04:38:33.

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 1492840 1492840 0 0.00 0 32655 12 0 0
doc_list_biud 1492824 1492824 0 0.00 0 40334 36 0 0
sp_add_invoice_to_stock 5279 5142 137 2.59 69 175934 24213 0 137
sp_cancel_adding_invoice 5176 5176 0 0.00 4 51780 1074 0 0
sp_cancel_client_order 1814 1814 0 0.00 6 40021 427 0 0
sp_cancel_customer_reserve 501246 501246 0 0.00 1 39105 88 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 4 2910 121 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 4 2401 96 0 0
sp_cancel_supplier_invoice 5762 5751 11 0.19 2 40336 960 0 11
sp_cancel_supplier_order 377 377 0 0.00 8 1314186 150148 0 0
sp_cancel_write_off 9316 9316 0 0.00 2 8325 151 0 0
sp_client_order 21232 21232 0 0.00 5 40105 444 0 0
sp_customer_reserve 470691 470691 0 0.00 1 88478 233 0 0
sp_fill_shopping_cart 32526 32526 0 0.00 1 16259 87 0 0
sp_fill_shopping_cart_clo_res 470664 470664 0 0.00 0 20632 18 0 0
sp_get_clo_for_invoice 5108 5108 0 0.00 0 27077 332 0 0
sp_kill_cost_storno 15623 15623 0 0.00 0 14928 66 0 0
sp_kill_qstorno_ret_qs2qd 1017936 1017936 0 0.00 0 40333 39 0 0
sp_kill_qty_storno 539319 539319 0 0.00 0 40333 90 0 0
sp_lock_selected_doc 21564 21564 0 0.00 0 8311 8 0 0
sp_make_cost_storno 28924 28924 0 0.00 0 32645 67 0 0
sp_multiply_rows_for_pdistr 28920 28920 0 0.00 0 9533 33 0 0
sp_multiply_rows_for_qdistr 21231 21231 0 0.00 0 17829 159 0 0
sp_pay_from_customer 7235 7235 0 0.00 5 13676 155 0 0
sp_pay_to_supplier 6046 6046 0 0.00 6 9812 168 0 0
sp_payment_common 13279 13279 0 0.00 2 13664 39 0 0
sp_qd_handle_on_cancel_clo 1814 1814 0 0.00 1 40012 357 0 0
sp_qd_handle_on_invoice_upd_sts 10282 10282 0 0.00 0 35594 475 0 0
sp_qd_handle_on_reserve_upd_sts 10533 10533 0 0.00 0 31853 68 0 0
sp_reserve_write_off 10537 10537 0 0.00 9 31959 262 0 0
sp_supplier_invoice 5724 5724 0 0.00 10 79921 2937 0 0
sp_supplier_order 5593 5593 0 0.00 6 35736 1270 0 0
srv_aggregate_perf_data 111 111 0 0.00 2442 254062 15272 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 962 155638 39569 0 0
srv_find_qd_qs_mism 1008889 1008889 0 0.00 0 21967 10 0 0
srv_make_invnt_saldo 512 512 0 0.00 61 62035 3588 0 0
srv_make_money_saldo 890 890 0 0.00 1 9091 331 0 0
srv_recalc_idx_stat 4 4 0 0.00 81847 637987 335308 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 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 5148 5148 0 0.00 1 16458 831 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 1 5563 365 0 0
t$perf-norm:sp_cancel_client_order 1814 1814 0 0.00 0 2887 29 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 851 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 1933 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 343 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 756 28 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 91246 6561 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 2778 21 0 0
t$perf-norm:sp_client_order 21232 21232 0 0.00 0 24633 38 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 1607 12 0 0
t$perf-norm:sp_pay_from_customer 7236 7236 0 0.00 0 6259 19 0 0
t$perf-norm:sp_pay_to_supplier 6046 6046 0 0.00 0 7042 19 0 0
t$perf-norm:sp_reserve_write_off 10537 10537 0 0.00 0 16078 25 0 0
t$perf-norm:sp_supplier_invoice 5724 5724 0 0.00 0 5477 30 0 0
t$perf-norm:sp_supplier_order 5593 5593 0 0.00 0 29209 37 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 389 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 229 16 0 0
t$perf-norm:srv_make_invnt_saldo 512 512 0 0.00 0 866 10 0 0
t$perf-norm:srv_make_money_saldo 890 890 0 0.00 0 823 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 127 44 0 0
v_add_invoice_to_stock 10482 10208 274 2.61 0 6125 23 0 274
v_all_customers 38358 38358 0 0.00 0 243 4 0 0
v_all_suppliers 31256 31256 0 0.00 0 107 4 0 0
v_all_wares 42448 42448 0 0.00 0 190 4 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 134 12 0 0
v_cancel_client_order 3628 3628 0 0.00 0 1715 9 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 1332 12 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 2328 12 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 398 9 0 0
v_cancel_supplier_invoice 832 810 22 2.64 0 881 20 0 22
v_cancel_supplier_order 754 754 0 0.00 0 2574 13 0 0
v_cancel_write_off 2138 2138 0 0.00 0 722 20 0 0
v_max_id_clo_ord 5582 5582 0 0.00 0 2233 5 0 0
v_max_id_clo_res 982 982 0 0.00 0 471 8 0 0
v_max_id_ord_sup 5716 5716 0 0.00 0 5639 7 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 221 7 0 0
v_max_non_paid_realizn 3938 3938 0 0.00 0 376 7 0 0
v_min_id_clo_ord 5582 5582 0 0.00 0 1018 7 0 0
v_min_id_clo_res 982 982 0 0.00 0 8682 24 0 0
v_min_id_ord_sup 5716 5716 0 0.00 0 6066 11 0 0
v_min_non_paid_invoice 6044 6044 0 0.00 0 2983 12 0 0
v_min_non_paid_realizn 7234 7234 0 0.00 0 13658 14 0 0
v_our_firm 10684 10684 0 0.00 0 61 4 0 0
v_reserve_write_off 21066 21066 0 0.00 0 5784 11 0 0
x_lock_depdocs_on_canc_invoice 10522 10522 0 0.00 0 14552 131 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 25096 433 0 0
x_make_qstorno_1000_1200 5593 5593 0 0.00 2 35728 1115 0 0
x_make_qstorno_1200_2000 5724 5724 0 0.00 4 78482 2677 0 0
x_make_qstorno_2100_3300 470691 470691 0 0.00 0 88473 203 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-07-2022 04:38:34 to 29-07-2022 04:38:38.

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
02:07:22 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 30245 8192000000 9739645712 10041409536 816219344 9.060 100 89 178 0 845462848 7801632 21441872
02:09:57 21053 8192000000 9778012224 10086735872 854377232 9.444 100 90 180 0 888038384 12714496 20946656
02:11:22 13136 8192000000 9808302624 10131034112 878277984 9.683 100 78 156 0 916655168 20706000 17671184
02:12:38 11652 8192000000 9858181216 10193838080 920731904 10.103 100 84 167 1 969510704 30079744 18699056
02:14:01 2246 8192000000 9916466192 10260975616 962933280 10.518 100 85 159 0 1034410976 54914880 16562816
02:15:05 1578 8192000000 9959913632 10311622656 1001812528 10.896 100 82 159 0 1071171984 53638208 15721248
02:16:10 1237 8192000000 10022795824 10382241792 1023856528 11.109 100 95 185 1 1139829760 97784240 18188992
02:17:13 1055 8192000000 10011102768 10381135872 1049441872 11.355 100 95 186 2 1129470672 61740224 18288576
02:18:15 984 8192000000 10051232032 10429841408 1064543232 11.500 100 93 175 0 1167627424 86058000 17026192
02:19:18 1183 8192000000 10059109712 10440814592 1071792736 11.569 100 100 192 0 1179188976 88723600 18672640
02:20:21 881 8192000000 10048619680 10431803392 1074738064 11.597 100 87 174 1 1171601072 79880576 16982432
02:21:22 7421 8192000000 10022578848 10411679744 1075275504 11.602 100 69 135 0 1148400128 59348784 13775840
02:22:31 988 8192000000 10057657360 10441732096 1083780608 11.683 100 97 187 1 1176245536 72747536 19717392
02:23:36 5942 8192000000 10030495680 10420846592 1083810496 11.684 100 67 122 2 1153911680 57296096 12805088
02:24:42 2282 8192000000 10061314176 10459230208 1090768240 11.750 100 86 161 2 1182314896 72886448 18660208
02:25:46 1127 8192000000 10074854608 10469756928 1100386544 11.841 100 89 170 1 1195442208 77456992 17598672
02:26:48 2406 8192000000 10058731872 10458050560 1100335840 11.841 100 67 125 3 1188853648 75233824 13283984
02:27:51 3517 8192000000 10043419024 10439299072 1104220256 11.878 100 69 134 2 1169877264 52388000 13269008
02:28:56 1188 8192000000 10061733664 10456985600 1105269776 11.888 100 84 158 0 1182579648 61538656 15771216
02:29:58 1002 8192000000 10044459344 10431352832 1085901200 11.704 100 89 167 2 1160305152 58834752 15569200
02:31:00 993 8192000000 10020806416 10406486016 1073412736 11.585 100 84 152 2 1136704448 48800544 14491168
02:32:02 1221 8192000000 10024429584 10406715392 1060017200 11.457 100 84 155 1 1152939232 77636320 15285712
02:33:04 2500 8192000000 9998506320 10374905856 1045431376 11.317 100 74 141 0 1120348960 61689088 13228496
02:34:07 1027 8192000000 10013502608 10390315008 1047344624 11.335 100 94 176 1 1135714176 70627104 17742448
02:35:09 1002 8192000000 9966239264 10334257152 1012745504 11.002 99 91 174 7 1090564848 59264544 18554800
02:36:11 778 8192000000 9961748608 10336395264 1035912592 11.225 100 83 158 2 1085330960 32111632 17306736
02:37:12 1874 8192000000 9989998960 10370973696 1046329888 11.325 100 74 133 1 1112964624 51892896 14741840
02:38:21 2321 8192000000 9988476688 10363383808 1051848896 11.378 99 80 141 3 1107916448 41375360 14692192
02:39:27 7998 8192000000 10034439232 10413920256 1070305824 11.555 100 75 138 2 1151128240 67565936 13256480
02:40:38 1466 8192000000 10085719440 10472583168 1089691280 11.740 100 93 175 1 1203609376 94577824 19340272
02:41:42 9679 8192000000 10103222624 10498211840 1096681472 11.806 100 72 144 1 1214373968 102734352 14958144
02:43:06 1885 8192000000 10115823904 10507669504 1112635248 11.957 100 80 146 1 1234023584 106656880 14731456
02:44:12 3680 8192000000 10170948384 10569195520 1123866832 12.064 100 72 139 1 1290195408 151764128 14564448
02:45:19 1334 8192000000 10182959648 10582151168 1138703936 12.203 100 88 163 3 1297569248 141896080 16969232
02:46:25 6930 8192000000 10213596480 10612568064 1154659456 12.353 100 86 150 1 1328587856 158565040 15363360
02:47:35 5262 8192000000 10231841120 10628673536 1161843600 12.421 100 91 171 1 1343346000 163794464 17707936
02:48:45 1082 8192000000 10260352464 10653724672 1165496320 12.455 100 98 188 1 1368138672 182170400 20471952
02:49:50 1214 8192000000 10295108848 10694455296 1167636144 12.475 100 93 176 0 1405910928 218588336 19686448
02:50:53 1098 8192000000 10327481664 10744823808 1182193952 12.611 100 88 171 1 1441628480 239140592 20293936
02:51:56 1089 8192000000 10370753712 10775236608 1181666736 12.606 100 93 180 0 1477446816 274760288 21019792
02:53:00 1399 8192000000 10372927520 10795220992 1189526016 12.679 100 91 171 4 1476895536 268677888 18691632
02:54:05 1019 8192000000 10400008224 10832412672 1183025664 12.618 99 88 170 2 1509587168 306609728 19951776
02:55:11 1412 8192000000 10445568512 10855485440 1190401024 12.687 100 90 172 1 1553330336 342771936 20157376
02:56:16 957 8192000000 10379107968 10836819968 1177039696 12.563 100 83 154 1 1487335888 290913696 19382496
02:57:20 2345 8192000000 10407108624 10853896192 1183134992 12.619 100 69 134 3 1516211104 317491856 15584256
02:58:29 4641 8192000000 10401468992 10838016000 1162325584 12.425 99 66 119 3 1510218704 332342704 15550416
02:59:38 1017 8192000000 10348409872 10767163392 1141155840 12.226 100 97 176 2 1457739440 293734912 22848688
03:00:41 5601 8192000000 10401484336 10830782464 1163146720 12.433 100 53 106 1 1512516640 336601680 12768240
03:02:06 3540 8192000000 10427965888 10859311104 1159282416 12.397 100 52 104 0 1537235872 364919824 13033632
03:03:11 17283 8192000000 10440224128 10894888960 1149836656 12.308 100 38 74 0 1556038928 396431472 9770800
03:04:30 19189 8192000000 10343266976 10890878976 1155348960 12.360 100 35 68 0 1484825104 320689456 8786688
03:05:55 7639 8192000000 10265912608 10739060736 1127116496 12.094 99 39 76 0 1366239984 229819904 9303584
03:07:04 12583 8192000000 10303333744 10757857280 1141972016 12.234 100 37 73 0 1416330992 265583920 8775056
03:08:38 25241 8192000000 10278237888 10737909760 1142506256 12.239 99 21 42 0 1351125664 203361376 5258032
03:10:07 69164 8192000000 10217669376 10689859584 1155029984 12.357 100 31 62 0 1345778384 183311936 7436464
03:12:25 74358 8192000000 10181818656 10671132672 1135714512 12.175 99 27 54 0 1290468448 148817088 5936848
03:14:49 83993 8192000000 10118392960 10586832896 1101188992 11.849 97 17 32 0 1238556544 133643168 3724384
03:17:47 72429 8192000000 10068568736 10515734528 1088022096 11.724 97 22 44 0 1121095232 28465664 4607472
03:20:00 59242 8192000000 10076017776 10513960960 1107999072 11.913 98 17 32 1 1156582736 45117472 3466192
03:22:02 59164 8192000000 10072931840 10515812352 1079299600 11.641 96 28 54 0 1127066720 42199440 5567680
03:24:06 25429 8192000000 10064556864 10515214336 1121451264 12.041 100 24 44 0 1161868976 36269456 4148256
03:25:36 113586 8192000000 10069465616 10563985408 1089064144 11.734 96 11 20 0 1126129472 35484336 1580992
03:28:32 155638 8192000000 10078153968 10604879872 1095983664 11.800 96 9 17 0 1181876880 84155792 1737424
03:32:58 131989 8192000000 10108586816 10549108736 1133333888 12.153 99 36 61 0 1173500864 34193440 5973536
03:36:11 53357 8192000000 10131910464 10582573056 1137001232 12.187 99 29 54 0 1219367424 76412016 5954176
03:38:12 73601 8192000000 10155327168 10643836928 1142765728 12.242 99 25 50 0 1239109184 91053728 5289728
03:40:44 51154 8192000000 10140055120 10626453504 1134351808 12.162 99 10 19 1 1225108416 88720208 2036400
03:42:51 53893 8192000000 10105680560 10650767360 1131714688 12.138 100 11 23 0 1233690272 99856768 2118816
03:44:45 83280 8192000000 10075477008 10517590016 1095997312 11.800 96 23 46 0 1139680544 39578480 4104752
03:47:10 21479 8192000000 10094351008 10534522880 1131747968 12.138 100 36 73 0 1186559984 47548640 7263376
03:48:39 39265 8192000000 10080841056 10506321920 1110572000 11.938 97 33 66 0 1156881344 38975936 7333408
03:50:20 31872 8192000000 10111488000 10543955968 1108555424 11.919 98 32 64 0 1184253328 69537424 6160480
03:51:52 62058 8192000000 10112752912 10609295360 1106892784 11.903 98 18 37 0 1185606528 74773360 3940384
03:53:54 54007 8192000000 10067887952 10504376320 1085325392 11.698 97 15 30 0 1182996128 94407152 3263584
03:55:49 13874 8192000000 10078800384 10495467520 1097391072 11.813 99 46 87 0 1155140032 47872688 9876272
03:57:07 123930 8192000000 10043339008 10499502080 1100827776 11.845 98 26 52 0 1153666688 46690512 6148400
04:00:14 7096 8192000000 10084295744 10511511552 1111316624 11.945 99 48 96 0 1173132432 52201120 9614688
04:01:27 62863 8192000000 10071024304 10538958848 1114108208 11.971 100 16 32 0 1176382512 58423280 3851024
04:03:30 65163 8192000000 10082016432 10544406528 1099149632 11.830 98 23 46 0 1178241360 74172224 4919504
04:05:54 37875 8192000000 10099622080 10571423744 1109273520 11.926 99 21 36 0 1169067760 56059088 3735152
04:07:57 40708 8192000000 10049524816 10485018624 1093695104 11.778 97 18 35 0 1162038144 65020448 3322592
04:10:13 46885 8192000000 10070198880 10515726336 1073339344 11.584 95 27 54 0 1127172688 48699424 5133920
04:12:08 32364 8192000000 10104934816 10546941952 1115368800 11.983 99 31 62 0 1188195984 66553728 6273456
04:13:41 71095 8192000000 10087222704 10559541248 1124561120 12.070 99 24 48 0 1200583072 71095760 4926192
04:15:53 109227 8192000000 10071654544 10534678528 1117663696 12.005 98 14 28 0 1206519824 85736128 3120000
04:18:43 56582 8192000000 10099153520 10571386880 1123411216 12.059 99 31 62 0 1182186128 52793600 5981312
04:21:02 126911 8192000000 10068726880 10514976768 1138576816 12.202 100 30 60 1 1191310256 46651328 6082112
04:24:15 37952 8192000000 10135888832 10599141376 1121580032 12.042 98 35 69 0 1207123440 78525696 7017712
04:26:01 95379 8192000000 10060573856 10577801216 1114016704 11.970 98 10 15 0 1187901648 71932320 1952624
04:28:44 66829 8192000000 10097033584 10550198272 1132059664 12.141 99 15 30 0 1172845840 37634240 3151936
04:30:52 42906 8192000000 10079226112 10535546880 1119152352 12.019 98 25 48 0 1163477744 39351952 4973440
04:32:39 22287 8192000000 10087527536 10523238400 1128393424 12.106 99 38 74 0 1172878096 36818512 7666160
04:34:04 21055 8192000000 10097200752 10538807296 1136928800 12.187 100 29 58 0 1190161168 46996304 6236064
04:35:26 52004 8192000000 10160378608 10609680384 1118507536 12.013 98 32 64 0 1227629616 102014032 7108048

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-07-2022 04:38:40 to 29-07-2022 04:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 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 29-07-2022 04:38:56 to 29-07-2022 04:38:58.

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          222317
MON$OLDEST_ACTIVE               227474
MON$OLDEST_SNAPSHOT             227474
MON$NEXT_TRANSACTION            227474
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                       2068960
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:185: 
:186: mscs-test-161.budzdorov.ru    Fri Jul 29 04:37:22 2022
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:188: 
:189: 
:190: mscs-test-161.budzdorov.ru    Fri Jul 29 04:37:23 2022
:191:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:192: 
:193: 
:194: mscs-test-161.budzdorov.ru    Fri Jul 29 04:37:31 2022
:195:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:196: 
:197: 
:198: mscs-test-161.budzdorov.ru    Fri Jul 29 04:37:34 2022
:199:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:200: 
--- end of diff output ---

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

29.07.22 04:38:59. 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-07-29 04:39:02 No severe PSQL-related problems occured
29.07.22 04:39:03. End of report.