Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.05.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 16329632 kB
MemAvailable 31468444 kB
Buffers 20 kB
Cached 15349692 kB
SwapCached 2604 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-12 04:37:17
DTS_END                         2022-05-12 04:37:17.4840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(226:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/32982
TRN_ID                          202590
ATT_ID                          610
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: 8631.57 30190 1035788
customer order: creation 155.76 535 18691
customer order: refuse 13.07 486 1568
order to supplier: creation 42.32 1482 5078
order to supplier: removal 3.28 207413 393
invoice (draft): creation 40.08 3677 4810
invoice (draft): removal 49.81 1107 5977
invoice accept: apply 36.82 30378 4418
invoice accept: cancel 45.32 1196 5438
customer reserve: creation 3495.33 283 419439
customer reserve: removal 4477.93 117 537351
realization accept: apply 74.93 315 8991
realization accept: cancel 80.00 178 9600
payment from customer: creation 52.78 210 6333
payment from customer: removal 4.72 205 566
payment to supplier: creation 44.36 210 5323
payment to supplier: removal 3.39 161 407
service: total inventory turnovers 3.58 4550 430
service: total monetary turnovers 7.29 392 875
service: aggregate perf. data 0.79 19996 95
service: refresh index statistics 0.04 330899 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-05-2022 04:38:48 to 12-05-2022 04:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9633
TEST_TIME 2 7793
TEST_TIME 3 6256
TEST_TIME 4 5952
TEST_TIME 5 6019
TEST_TIME 6 5729
TEST_TIME 7 5426
TEST_TIME 8 5631
TEST_TIME 9 5877
TEST_TIME 10 5780
TEST_TIME 11 5857
TEST_TIME 12 5939
TEST_TIME 13 6338
TEST_TIME 14 6787
TEST_TIME 15 6878
TEST_TIME 16 6962
TEST_TIME 17 6900
TEST_TIME 18 6802
TEST_TIME 19 6845
TEST_TIME 20 7011
TEST_TIME 21 6913
TEST_TIME 22 6974
TEST_TIME 23 7177
TEST_TIME 24 7340
TEST_TIME 25 7197
TEST_TIME 26 7241
TEST_TIME 27 7521
TEST_TIME 28 7938
TEST_TIME 29 7848
TEST_TIME 30 8077
TEST_TIME 31 8296
TEST_TIME 32 8505
TEST_TIME 33 9276
TEST_TIME 34 9427
TEST_TIME 35 9672
TEST_TIME 36 9890
TEST_TIME 37 10375
TEST_TIME 38 10323
TEST_TIME 39 10532
TEST_TIME 40 10283
TEST_TIME 41 10139
TEST_TIME 42 10233
TEST_TIME 43 10090
TEST_TIME 44 10034
TEST_TIME 45 9973
TEST_TIME 46 9849
TEST_TIME 47 9742
TEST_TIME 48 9688
TEST_TIME 49 9598
TEST_TIME 50 9572
TEST_TIME 51 9592
TEST_TIME 52 9469
TEST_TIME 53 9332
TEST_TIME 54 9362
TEST_TIME 55 9220
TEST_TIME 56 9155
TEST_TIME 57 9104
TEST_TIME 58 9069
TEST_TIME 59 9029
TEST_TIME 60 8988
TEST_TIME 61 8976
TEST_TIME 62 8873
TEST_TIME 63 8922
TEST_TIME 64 8890
TEST_TIME 65 8940
TEST_TIME 66 8866
TEST_TIME 67 8876
TEST_TIME 68 8912
TEST_TIME 69 8897
TEST_TIME 70 8903
TEST_TIME 71 8945
TEST_TIME 72 8909
TEST_TIME 73 8943
TEST_TIME 74 8962
TEST_TIME 75 8924
TEST_TIME 76 8908
TEST_TIME 77 8877
TEST_TIME 78 8892
TEST_TIME 79 8843
TEST_TIME 80 8758
TEST_TIME 81 8784
TEST_TIME 82 8777
TEST_TIME 83 8748
TEST_TIME 84 8713
TEST_TIME 85 8681
TEST_TIME 86 8662
TEST_TIME 87 8593
TEST_TIME 88 8537
TEST_TIME 89 8570
TEST_TIME 90 8601
TEST_TIME 91 8579
TEST_TIME 92 8580
TEST_TIME 93 8587
TEST_TIME 94 8570
TEST_TIME 95 8550
TEST_TIME 96 8590
TEST_TIME 97 8612
TEST_TIME 98 8740
TEST_TIME 99 8794
TEST_TIME 100 8775
TEST_TIME 101 8807
TEST_TIME 102 8736
TEST_TIME 103 8732
TEST_TIME 104 8721
TEST_TIME 105 8694
TEST_TIME 106 8686
TEST_TIME 107 8654
TEST_TIME 108 8652
TEST_TIME 109 8670
TEST_TIME 110 8687
TEST_TIME 111 8685
TEST_TIME 112 8687
TEST_TIME 113 8677
TEST_TIME 114 8712
TEST_TIME 115 8724
TEST_TIME 116 8749
TEST_TIME 117 8755
TEST_TIME 118 8765
TEST_TIME 119 8705
TEST_TIME 120 8657

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-05-2022 04:38:51 to 12-05-2022 04:38:55.

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 1423316 1423316 0 0.00 0 27206 15 0 0
doc_list_biud 1423314 1423314 0 0.00 0 66207 50 0 0
sp_add_invoice_to_stock 4544 4418 126 2.77 67 257897 30378 0 126
sp_cancel_adding_invoice 5438 5438 0 0.00 5 105280 1196 0 0
sp_cancel_client_order 1568 1568 0 0.00 6 19260 486 0 0
sp_cancel_customer_reserve 537351 537351 0 0.00 1 22223 117 0 0
sp_cancel_pay_from_customer 566 566 0 0.00 4 9164 206 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 5462 162 0 0
sp_cancel_supplier_invoice 5987 5977 10 0.16 3 66216 1107 0 10
sp_cancel_supplier_order 393 393 0 0.00 11 1350871 207414 0 0
sp_cancel_write_off 9600 9600 0 0.00 2 11034 179 0 0
sp_client_order 18691 18691 0 0.00 6 22020 536 0 0
sp_customer_reserve 419439 419439 0 0.00 1 76946 283 0 0
sp_fill_shopping_cart 28576 28576 0 0.00 1 15057 85 0 0
sp_fill_shopping_cart_clo_res 419411 419411 0 0.00 0 15307 22 0 0
sp_get_clo_for_invoice 4373 4373 0 0.00 0 18088 347 0 0
sp_kill_cost_storno 16011 16011 0 0.00 0 27193 91 0 0
sp_kill_qstorno_ret_qs2qd 1090672 1090672 0 0.00 0 66195 50 0 0
sp_kill_qty_storno 573691 573691 0 0.00 0 66202 115 0 0
sp_lock_selected_doc 18451 18451 0 0.00 0 9153 8 0 0
sp_make_cost_storno 25020 25020 0 0.00 0 15542 89 0 0
sp_multiply_rows_for_pdistr 25019 25019 0 0.00 0 8449 45 0 0
sp_multiply_rows_for_qdistr 18691 18691 0 0.00 0 17625 174 0 0
sp_pay_from_customer 6333 6333 0 0.00 6 16745 210 0 0
sp_pay_to_supplier 5323 5323 0 0.00 7 13058 210 0 0
sp_payment_common 11655 11655 0 0.00 2 12963 49 0 0
sp_qd_handle_on_cancel_clo 1568 1568 0 0.00 0 19253 403 0 0
sp_qd_handle_on_invoice_upd_sts 9811 9811 0 0.00 0 23814 589 0 0
sp_qd_handle_on_reserve_upd_sts 8991 8991 0 0.00 0 12422 78 0 0
sp_reserve_write_off 8991 8991 0 0.00 9 15569 315 0 0
sp_supplier_invoice 4810 4810 0 0.00 10 85747 3678 0 0
sp_supplier_order 5078 5078 0 0.00 9 39282 1482 0 0
srv_aggregate_perf_data 95 95 0 0.00 2381 154252 19996 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 787 130984 36331 0 0
srv_find_qd_qs_mism 992193 992193 0 0.00 0 11764 11 0 0
srv_make_invnt_saldo 430 430 0 0.00 92 67713 4551 0 0
srv_make_money_saldo 875 875 0 0.00 1 13966 392 0 0
srv_recalc_idx_stat 5 5 0 0.00 55760 518687 330899 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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 4419 4419 0 0.00 1 19205 1070 0 0
t$perf-norm:sp_cancel_adding_invoice 347 347 0 0.00 2 6220 385 0 0
t$perf-norm:sp_cancel_client_order 1568 1568 0 0.00 0 6943 32 0 0
t$perf-norm:sp_cancel_customer_reserve 581 581 0 0.00 0 1161 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 566 566 0 0.00 0 504 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 606 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 2085 42 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 125569 8627 0 0
t$perf-norm:sp_cancel_write_off 863 863 0 0.00 0 7446 35 0 0
t$perf-norm:sp_client_order 18691 18691 0 0.00 0 5631 42 0 0
t$perf-norm:sp_customer_reserve 900 900 0 0.00 0 913 17 0 0
t$perf-norm:sp_pay_from_customer 6333 6333 0 0.00 0 6988 25 0 0
t$perf-norm:sp_pay_to_supplier 5323 5323 0 0.00 0 8197 22 0 0
t$perf-norm:sp_reserve_write_off 8991 8991 0 0.00 0 13508 27 0 0
t$perf-norm:sp_supplier_invoice 4810 4810 0 0.00 0 6604 36 0 0
t$perf-norm:sp_supplier_order 5078 5078 0 0.00 0 3689 36 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 461 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 335 13 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 3204 47 0 0
t$perf-norm:srv_make_money_saldo 875 875 0 0.00 0 8131 16 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 197 45 0 0
v_add_invoice_to_stock 8996 8744 252 2.80 0 10677 28 0 252
v_all_customers 34352 34352 0 0.00 0 145 4 0 0
v_all_suppliers 28082 28082 0 0.00 0 70 4 0 0
v_all_wares 37378 37378 0 0.00 0 118 4 0 0
v_cancel_adding_invoice 694 694 0 0.00 0 1298 16 0 0
v_cancel_client_order 3136 3136 0 0.00 0 10728 16 0 0
v_cancel_customer_prepayment 1132 1132 0 0.00 0 7444 22 0 0
v_cancel_customer_reserve 1162 1162 0 0.00 0 1218 11 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 596 11 0 0
v_cancel_supplier_invoice 748 728 20 2.67 0 587 24 0 20
v_cancel_supplier_order 786 786 0 0.00 0 12728 28 0 0
v_cancel_write_off 1726 1726 0 0.00 0 10777 28 0 0
v_max_id_clo_ord 5078 5078 0 0.00 0 4188 6 0 0
v_max_id_clo_res 900 900 0 0.00 0 3309 14 0 0
v_max_id_ord_sup 4807 4807 0 0.00 0 6845 7 0 0
v_max_non_paid_invoice 1167 1167 0 0.00 0 8343 16 0 0
v_max_non_paid_realizn 3099 3099 0 0.00 0 3618 9 0 0
v_min_id_clo_ord 5078 5078 0 0.00 0 7790 12 0 0
v_min_id_clo_res 900 900 0 0.00 0 583 8 0 0
v_min_id_ord_sup 4807 4807 0 0.00 0 7918 16 0 0
v_min_non_paid_invoice 5323 5323 0 0.00 0 8496 19 0 0
v_min_non_paid_realizn 6332 6332 0 0.00 0 8497 21 0 0
v_our_firm 9492 9492 0 0.00 0 54 5 0 0
v_reserve_write_off 17980 17980 0 0.00 0 12723 13 0 0
x_lock_depdocs_on_canc_invoice 11051 11051 0 0.00 0 9911 129 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 8277 460 0 0
x_make_qstorno_1000_1200 5078 5078 0 0.00 3 39256 1295 0 0
x_make_qstorno_1200_2000 4810 4810 0 0.00 5 84862 3448 0 0
x_make_qstorno_2100_3300 419439 419439 0 0.00 0 76927 248 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-05-2022 04:38:56 to 12-05-2022 04:39:00.

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:21 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 22550 8192000000 9714898032 10018000896 800519456 8.902 100 94 189 0 831120688 7590144 23011088
02:09:57 22428 8192000000 9775233552 10087960576 846936128 9.369 100 90 180 0 880629920 12436080 21257712
02:11:25 14485 8192000000 9808724896 10129014784 880355024 9.703 100 89 178 0 922793696 21680672 20758000
02:12:48 8931 8192000000 9850022640 10184318976 916965312 10.066 100 77 154 0 967523280 33260560 17297408
02:14:07 8555 8192000000 9925706352 10273390592 960847616 10.497 100 86 172 0 1040771696 61684592 18239488
02:15:20 3135 8192000000 9981961792 10338611200 992923856 10.810 100 81 160 0 1096862352 87056608 16881888
02:16:29 1584 8192000000 9983048912 10345459712 1016049664 11.034 100 86 169 0 1101348144 68755952 16542528
02:17:32 1621 8192000000 10020971152 10392166400 1036328704 11.229 100 96 187 0 1148631312 93881216 18421392
02:18:36 885 8192000000 10017079376 10388611072 1051076736 11.371 100 96 185 2 1131559520 62662240 17820544
02:19:38 1081 8192000000 10036019456 10410000384 1062501824 11.480 100 93 181 1 1155812016 74928304 18381888
02:20:43 8599 8192000000 10038262160 10415550464 1074051808 11.591 100 82 155 2 1159863552 70577040 15234704
02:21:53 865 8192000000 10050640576 10430590976 1081018912 11.657 100 93 181 1 1175307808 74731792 19557104
02:22:57 1173 8192000000 10079486480 10464309248 1083194192 11.678 100 96 179 1 1201856720 100860944 17801584
02:24:00 1492 8192000000 10067505280 10455875584 1084691808 11.692 100 90 175 0 1189244272 86609568 17942896
02:25:03 1369 8192000000 10071704928 10460880896 1091657568 11.758 100 96 177 1 1191427168 81631360 18138240
02:26:06 1176 8192000000 10071352320 10459783168 1095850976 11.798 100 92 177 0 1188379120 75231856 17296288
02:27:10 4102 8192000000 10044461760 10433093632 1086909184 11.713 99 78 147 1 1167685984 65502688 15274112
02:28:16 4017 8192000000 10029005104 10412154880 1079619504 11.644 98 74 142 1 1147734736 54358864 13756368
02:29:20 1248 8192000000 10078658928 10468749312 1101622528 11.853 100 97 186 0 1201267456 80687440 18957488
02:30:24 1367 8192000000 10079701376 10467377152 1096563920 11.805 100 93 184 2 1203801104 88555408 18681776
02:31:26 2056 8192000000 10054840896 10435006464 1075173664 11.601 99 86 164 0 1171245072 80277184 15794224
02:32:29 6340 8192000000 10027990224 10412580864 1079315824 11.641 99 68 122 3 1139282432 48436704 11529904
02:33:38 1032 8192000000 9992500176 10370252800 1051656272 11.377 100 96 185 3 1120091248 48780464 19654512
02:34:41 1352 8192000000 10029671440 10410504192 1055412448 11.413 100 95 170 1 1140128256 66512768 18203040
02:35:43 1156 8192000000 9983221968 10355474432 1033359296 11.201 99 94 175 2 1108334576 56253024 18722256
02:36:46 1768 8192000000 9995875616 10368475136 1028403904 11.153 100 80 151 1 1117618640 72637504 16577232
02:37:53 1578 8192000000 10013267344 10386513920 1051235520 11.373 100 83 164 1 1131566832 64453104 15878208
02:39:05 9427 8192000000 10047351952 10436169728 1065850688 11.512 100 61 122 0 1171333344 93250496 12232160
02:40:21 8895 8192000000 10013557392 10406756352 1072853840 11.579 100 61 116 3 1141619120 55633712 13131568
02:41:33 5073 8192000000 10068728240 10448203776 1086647952 11.711 100 75 143 0 1186618304 85011904 14958448
02:42:45 1677 8192000000 10120175920 10508623872 1109773328 11.930 100 82 161 1 1239670848 112591344 17306176
02:43:52 3750 8192000000 10152923696 10542641152 1112129248 11.953 100 82 154 0 1273480096 144481664 16869184
02:45:02 11411 8192000000 10207085616 10595909632 1126183184 12.085 100 82 160 1 1320249184 176870016 17195984
02:46:26 6444 8192000000 10194283408 10608521216 1135174752 12.170 100 62 119 1 1315722320 167338880 13208688
02:47:37 2371 8192000000 10233672656 10629713920 1152137952 12.330 100 80 158 0 1351671536 182024048 17509536
02:48:43 2611 8192000000 10288166864 10690236416 1164022960 12.441 100 81 157 0 1399970080 218071872 17875248
02:49:51 778 8192000000 10332049200 10747621376 1180761536 12.597 100 93 185 2 1444493088 242528288 21203264
02:50:57 836 8192000000 10331761216 10759979008 1186487760 12.651 100 90 172 0 1439541344 234397248 18656336
02:52:01 1089 8192000000 10360381056 10774532096 1194734208 12.727 100 97 193 0 1469866848 254215536 20917104
02:53:05 1995 8192000000 10380499456 10801098752 1203571456 12.809 100 87 169 1 1487614064 265053888 18988720
02:54:12 948 8192000000 10396446048 10800377856 1193893040 12.720 100 97 185 0 1505636032 290360480 21382512
02:55:19 1693 8192000000 10463170400 10868895744 1211533904 12.883 100 96 188 1 1569231616 335664608 22033104
02:56:27 2841 8192000000 10487323424 10916831232 1220476864 12.966 100 95 183 1 1596639376 353149920 23012592
02:57:34 1861 8192000000 10542350704 10980864000 1225565456 13.013 100 95 190 1 1645812224 397480192 22766576
02:58:40 1040 8192000000 10571324224 11011076096 1233531600 13.087 100 97 195 0 1701311504 444390624 23389280
02:59:46 1093 8192000000 10595348896 11061084160 1230144976 13.055 100 94 186 0 1702211104 448022800 24043328
03:00:49 1359 8192000000 10617244592 11059884032 1218760064 12.950 100 94 182 1 1723525904 482385680 22380160
03:01:56 4726 8192000000 10657574544 11078651904 1217288352 12.937 100 75 148 0 1755036352 519417920 18330080
03:03:09 1011 8192000000 10703901248 11129671680 1219078704 12.953 100 95 188 0 1802949440 560689136 23181600
03:04:11 1914 8192000000 10689531040 11159826432 1219607824 12.958 100 95 187 0 1787146208 544633696 22904688
03:05:16 2488 8192000000 10667960048 11145019392 1218938832 12.952 100 79 155 0 1770303200 531400912 19963456
03:06:23 5314 8192000000 10611250624 11080531968 1208000320 12.851 100 54 106 0 1716810800 494769552 14040928
03:07:32 22543 8192000000 10668699024 11148419072 1225609808 13.014 100 54 108 0 1783428912 543811760 14007344
03:08:59 14157 8192000000 10651613648 11160125440 1206493984 12.837 100 39 78 1 1743674944 526437056 10743904
03:10:34 17817 8192000000 10463292496 11009875968 1197178576 12.750 100 28 56 0 1591117712 386458160 7480976
03:12:35 35259 8192000000 10362741520 10890014720 1184577440 12.633 100 20 40 0 1488548304 299160432 4810432
03:14:18 88592 8192000000 10194483424 10679160832 1165250928 12.452 99 25 47 3 1284286224 114642736 4392560
03:16:56 108849 8192000000 10158819088 10620882944 1143737840 12.251 98 38 75 0 1241239968 88741792 8760336
03:19:53 122766 8192000000 10109912112 10592743424 1139027856 12.206 98 29 56 1 1213839808 69229104 5582848
03:22:58 70007 8192000000 10112473104 10574450688 1107297792 11.907 96 29 58 0 1160052848 46181984 6573072
03:25:16 61470 8192000000 10113628480 10576506880 1141718288 12.232 99 16 32 0 1220063280 75019168 3325824
03:27:18 55174 8192000000 10106013392 10566234112 1129790080 12.119 98 13 26 0 1194217088 61689696 2737312
03:29:15 107792 8192000000 10094178256 10560565248 1126357456 12.087 98 27 54 0 1185583744 52914608 6311680
03:32:11 54993 8192000000 10065332736 10499919872 1134917712 12.168 100 55 105 0 1172272032 25736880 11617440
03:34:07 30866 8192000000 10132874352 10586968064 1154442656 12.351 100 41 79 0 1242486304 80021872 8021776
03:35:42 64598 8192000000 10121765584 10592395264 1105175552 11.887 96 24 48 2 1193496752 83003872 5317328
03:37:48 32113 8192000000 10090745392 10533363712 1091829504 11.760 96 17 34 0 1165861232 70107792 3923936
03:41:02 51965 8192000000 10088562272 10557222912 1111951456 11.951 99 11 22 0 1189975872 75755312 2269104
03:42:55 57405 8192000000 10096789008 10528600064 1088311888 11.727 96 25 46 1 1146132800 52968288 4852624
03:45:00 37249 8192000000 10077226800 10519740416 1137955680 12.196 100 20 38 2 1181694128 39895664 3842784
03:46:40 8095 8192000000 10107390448 10529751040 1122768512 12.053 99 61 122 1 1190973344 56184000 12020832
03:47:54 34378 8192000000 10084140672 10560073728 1110045344 11.933 98 36 70 1 1190289440 73262240 6981856
03:49:29 33106 8192000000 10138082512 10575040512 1120359664 12.030 98 45 90 2 1206985184 77478960 9146560
03:51:05 66494 8192000000 10103061968 10580774912 1126189696 12.085 99 20 40 2 1200909744 70624096 4095952
03:53:13 130981 8192000000 10056035776 10524098560 1069559104 11.548 94 27 55 1 1118416624 43867232 4990288
03:56:32 110248 8192000000 10087308096 10545840128 1123550480 12.061 100 45 86 1 1211712928 78746832 9415616
03:59:23 22173 8192000000 10061056192 10516926464 1111042016 11.942 99 46 87 3 1157797728 36791200 9964512
04:00:46 58792 8192000000 10058742832 10515451904 1105188864 11.887 98 15 30 0 1166049600 57544032 3316704
04:02:46 38033 8192000000 10076581168 10515468288 1132256320 12.143 99 38 75 1 1173691680 33034080 8401280
04:04:37 97768 8192000000 10103970416 10594754560 1120036928 12.027 98 14 25 2 1199055088 76291072 2727088
04:07:16 40839 8192000000 10084450448 10539249664 1116908048 11.998 98 22 44 1 1162430064 40189168 5332848
04:09:09 40600 8192000000 10107751872 10548396032 1119092720 12.018 98 25 50 1 1200836528 76075760 5668048
04:10:50 48526 8192000000 10154131632 10585788416 1130392608 12.125 99 30 60 0 1242136512 105343408 6400496
04:12:43 25550 8192000000 10211352192 10644135936 1134570768 12.164 99 50 96 4 1294706304 148952672 11182864
04:14:14 43437 8192000000 10181867296 10650951680 1152035040 12.329 100 25 50 0 1309822928 152348736 5439152
04:15:58 44237 8192000000 10088630880 10569175040 1119422240 12.022 98 20 40 1 1170692864 47691664 3578960
04:17:46 59628 8192000000 10111236304 10555236352 1135925504 12.177 100 33 66 0 1223948656 81500896 6522256
04:20:23 108530 8192000000 10087841856 10575286272 1119059808 12.018 98 13 26 0 1166154416 44811904 2282704
04:23:11 29240 8192000000 10113409376 10550583296 1119777088 12.025 98 40 78 1 1171907824 44444256 7686480
04:24:50 16381 8192000000 10091109056 10519457792 1120916832 12.036 99 59 105 2 1176043200 42474240 12652128
04:26:08 62465 8192000000 10095934480 10555637760 1125130736 12.075 99 28 56 0 1203837520 72500432 6206352
04:28:52 20294 8192000000 10081403904 10506993664 1111506896 11.947 99 46 87 1 1157733440 36917936 9308608
04:30:16 35248 8192000000 10113667568 10545799168 1121894976 12.045 99 44 88 1 1176710544 45914544 8901024
04:32:11 85207 8192000000 10053504384 10495877120 1124951568 12.074 100 16 32 1 1166143200 38114128 3077504
04:34:40 128430 8192000000 10044445152 10480451584 1098708032 11.825 98 15 30 1 1139463248 37857344 2897872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-05-2022 04:39:02 to 12-05-2022 04:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 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 12-05-2022 04:39:18 to 12-05-2022 04:39:20.

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          202589
MON$OLDEST_ACTIVE               203410
MON$OLDEST_SNAPSHOT             203410
MON$NEXT_TRANSACTION            203410
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                       2061088
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             746
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:329: 
:330: mscs-test-161.budzdorov.ru    Thu May 12 04:37:20 2022
:331:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:332: 
:333: 
:334: mscs-test-161.budzdorov.ru    Thu May 12 04:37:20 2022
:335:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:336: 
:337: 
:338: mscs-test-161.budzdorov.ru    Thu May 12 04:37:27 2022
:339:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:340: 
:341: 
:342: mscs-test-161.budzdorov.ru    Thu May 12 04:37:27 2022
:343:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:344: 
--- end of diff output ---

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

12.05.22 04:39:21. 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-12 04:39:24 No severe PSQL-related problems occured
12.05.22 04:39:25. End of report.