Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.10.2022 10: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 15287528 kB
MemAvailable 30577796 kB
Buffers 0 kB
Cached 16448612 kB
SwapCached 2036 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.3
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-10-05 10:37:21
DTS_END                         2022-10-05 10:37:21.7330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54428
TRN_ID                          223400
ATT_ID                          622
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: 8840.48 26523 1060858
customer order: creation 171.98 443 20637
customer order: refuse 15.08 514 1810
order to supplier: creation 46.90 1291 5628
order to supplier: removal 3.31 167023 397
invoice (draft): creation 47.48 3228 5698
invoice (draft): removal 47.68 1058 5721
invoice accept: apply 43.10 27479 5172
invoice accept: cancel 43.21 1446 5185
customer reserve: creation 3947.50 265 473700
customer reserve: removal 4174.08 104 500889
realization accept: apply 85.14 273 10217
realization accept: cancel 82.46 187 9895
payment from customer: creation 60.68 173 7282
payment from customer: removal 6.06 138 727
payment to supplier: creation 49.65 176 5958
payment to supplier: removal 3.38 128 405
service: total inventory turnovers 3.94 4425 473
service: total monetary turnovers 7.93 408 951
service: aggregate perf. data 0.91 15499 109
service: refresh index statistics 0.03 306205 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-10-2022 10:38:53 to 05-10-2022 10:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8307
TEST_TIME 2 7439
TEST_TIME 3 6981
TEST_TIME 4 6558
TEST_TIME 5 6220
TEST_TIME 6 6047
TEST_TIME 7 6178
TEST_TIME 8 6469
TEST_TIME 9 6493
TEST_TIME 10 6431
TEST_TIME 11 6634
TEST_TIME 12 6926
TEST_TIME 13 7370
TEST_TIME 14 7133
TEST_TIME 15 7123
TEST_TIME 16 7108
TEST_TIME 17 7037
TEST_TIME 18 7158
TEST_TIME 19 7384
TEST_TIME 20 7420
TEST_TIME 21 7609
TEST_TIME 22 7676
TEST_TIME 23 7884
TEST_TIME 24 8126
TEST_TIME 25 8364
TEST_TIME 26 8367
TEST_TIME 27 8680
TEST_TIME 28 8886
TEST_TIME 29 9023
TEST_TIME 30 9309
TEST_TIME 31 9300
TEST_TIME 32 9450
TEST_TIME 33 9463
TEST_TIME 34 10216
TEST_TIME 35 10342
TEST_TIME 36 10669
TEST_TIME 37 10572
TEST_TIME 38 10641
TEST_TIME 39 10663
TEST_TIME 40 10523
TEST_TIME 41 10311
TEST_TIME 42 10183
TEST_TIME 43 10128
TEST_TIME 44 10005
TEST_TIME 45 9942
TEST_TIME 46 9901
TEST_TIME 47 9862
TEST_TIME 48 9793
TEST_TIME 49 9813
TEST_TIME 50 9772
TEST_TIME 51 9749
TEST_TIME 52 9671
TEST_TIME 53 9613
TEST_TIME 54 9611
TEST_TIME 55 9650
TEST_TIME 56 9631
TEST_TIME 57 9594
TEST_TIME 58 9576
TEST_TIME 59 9466
TEST_TIME 60 9588
TEST_TIME 61 9614
TEST_TIME 62 9546
TEST_TIME 63 9545
TEST_TIME 64 9688
TEST_TIME 65 9682
TEST_TIME 66 9621
TEST_TIME 67 9714
TEST_TIME 68 9682
TEST_TIME 69 9558
TEST_TIME 70 9533
TEST_TIME 71 9455
TEST_TIME 72 9441
TEST_TIME 73 9400
TEST_TIME 74 9323
TEST_TIME 75 9250
TEST_TIME 76 9206
TEST_TIME 77 9166
TEST_TIME 78 9172
TEST_TIME 79 9133
TEST_TIME 80 9129
TEST_TIME 81 9088
TEST_TIME 82 9145
TEST_TIME 83 9148
TEST_TIME 84 9120
TEST_TIME 85 9076
TEST_TIME 86 9130
TEST_TIME 87 9291
TEST_TIME 88 9247
TEST_TIME 89 9323
TEST_TIME 90 9319
TEST_TIME 91 9250
TEST_TIME 92 9235
TEST_TIME 93 9171
TEST_TIME 94 9191
TEST_TIME 95 9183
TEST_TIME 96 9127
TEST_TIME 97 9080
TEST_TIME 98 9057
TEST_TIME 99 9025
TEST_TIME 100 9017
TEST_TIME 101 8996
TEST_TIME 102 8995
TEST_TIME 103 8978
TEST_TIME 104 8966
TEST_TIME 105 8954
TEST_TIME 106 8959
TEST_TIME 107 9012
TEST_TIME 108 9056
TEST_TIME 109 9140
TEST_TIME 110 9153
TEST_TIME 111 9120
TEST_TIME 112 9078
TEST_TIME 113 9030
TEST_TIME 114 9014
TEST_TIME 115 9017
TEST_TIME 116 8964
TEST_TIME 117 8934
TEST_TIME 118 8935
TEST_TIME 119 8917
TEST_TIME 120 8863

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-10-2022 10:38:56 to 05-10-2022 10:39:00.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1494820 1494820 0 0.00 0 21775 15 0 0
doc_list_biud 1494820 1494820 0 0.00 0 23238 42 0 0
sp_add_invoice_to_stock 5332 5172 160 3.00 53 217076 27480 0 160
sp_cancel_adding_invoice 5185 5185 0 0.00 6 93058 1447 0 0
sp_cancel_client_order 1810 1810 0 0.00 6 16616 514 0 0
sp_cancel_customer_reserve 500889 500889 0 0.00 1 19011 105 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 4 6123 139 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 4703 129 0 0
sp_cancel_supplier_invoice 5732 5721 11 0.19 2 18323 1059 0 11
sp_cancel_supplier_order 397 397 0 0.00 15 1423628 167023 0 0
sp_cancel_write_off 9895 9895 0 0.00 2 7505 187 0 0
sp_client_order 20637 20637 0 0.00 5 18146 443 0 0
sp_customer_reserve 473700 473700 0 0.00 0 92395 265 0 0
sp_fill_shopping_cart 31955 31955 0 0.00 1 19356 85 0 0
sp_fill_shopping_cart_clo_res 473680 473680 0 0.00 0 18258 18 0 0
sp_get_clo_for_invoice 5132 5132 0 0.00 0 30586 290 0 0
sp_kill_cost_storno 16212 16212 0 0.00 0 5929 76 0 0
sp_kill_qstorno_ret_qs2qd 1017791 1017791 0 0.00 0 18899 46 0 0
sp_kill_qty_storno 539242 539242 0 0.00 0 23236 107 0 0
sp_lock_selected_doc 21179 21179 0 0.00 0 9933 7 0 0
sp_make_cost_storno 28585 28585 0 0.00 0 9902 72 0 0
sp_multiply_rows_for_pdistr 28585 28585 0 0.00 0 6578 36 0 0
sp_multiply_rows_for_qdistr 20637 20637 0 0.00 0 10930 159 0 0
sp_pay_from_customer 7282 7282 0 0.00 5 11401 173 0 0
sp_pay_to_supplier 5958 5958 0 0.00 6 10426 177 0 0
sp_payment_common 13240 13240 0 0.00 2 3770 42 0 0
sp_qd_handle_on_cancel_clo 1810 1810 0 0.00 1 16543 409 0 0
sp_qd_handle_on_invoice_upd_sts 10313 10313 0 0.00 0 23236 568 0 0
sp_qd_handle_on_reserve_upd_sts 10217 10217 0 0.00 0 18982 61 0 0
sp_reserve_write_off 10217 10217 0 0.00 8 19002 273 0 0
sp_supplier_invoice 5698 5698 0 0.00 8 84353 3228 0 0
sp_supplier_order 5628 5628 0 0.00 7 18694 1291 0 0
srv_aggregate_perf_data 109 109 0 0.00 2450 146666 15500 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 726 119652 30756 0 0
srv_find_qd_qs_mism 1009859 1009859 0 0.00 0 21774 12 0 0
srv_make_invnt_saldo 473 473 0 0.00 51 66838 4426 0 0
srv_make_money_saldo 951 951 0 0.00 1 16350 408 0 0
srv_recalc_idx_stat 4 4 0 0.00 148181 508960 306205 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 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 5178 5178 0 0.00 0 12594 963 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 1 6182 552 0 0
t$perf-norm:sp_cancel_client_order 1810 1810 0 0.00 0 2325 29 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 1659 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 1759 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 983 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1244 45 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 118149 7283 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 4372 23 0 0
t$perf-norm:sp_client_order 20637 20637 0 0.00 0 28917 36 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 4738 19 0 0
t$perf-norm:sp_pay_from_customer 7282 7282 0 0.00 0 9166 22 0 0
t$perf-norm:sp_pay_to_supplier 5958 5958 0 0.00 0 8179 23 0 0
t$perf-norm:sp_reserve_write_off 10217 10217 0 0.00 0 9807 25 0 0
t$perf-norm:sp_supplier_invoice 5698 5698 0 0.00 0 3196 30 0 0
t$perf-norm:sp_supplier_order 5628 5628 0 0.00 0 10953 34 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 846 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 28 3 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 852 17 0 0
t$perf-norm:srv_make_money_saldo 951 951 0 0.00 0 489 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 191 58 0 0
v_add_invoice_to_stock 10574 10254 320 3.02 0 10677 30 0 320
v_all_customers 37744 37744 0 0.00 0 75 4 0 0
v_all_suppliers 31252 31252 0 0.00 0 169 5 0 0
v_all_wares 41274 41274 0 0.00 0 102 4 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 16603 34 0 0
v_cancel_client_order 3620 3620 0 0.00 0 16018 27 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 4687 17 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 16016 46 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1294 13 0 0
v_cancel_supplier_invoice 830 808 22 2.65 0 3972 31 0 22
v_cancel_supplier_order 794 794 0 0.00 0 524 12 0 0
v_cancel_write_off 1990 1990 0 0.00 0 4046 34 0 0
v_max_id_clo_ord 5628 5628 0 0.00 0 130 5 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 1463 12 0 0
v_max_id_ord_sup 5690 5690 0 0.00 0 7835 11 0 0
v_max_non_paid_invoice 1650 1650 0 0.00 0 131 8 0 0
v_max_non_paid_realizn 3899 3899 0 0.00 0 351 7 0 0
v_min_id_clo_ord 5628 5628 0 0.00 0 1098 7 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 5660 17 0 0
v_min_id_ord_sup 5690 5690 0 0.00 0 6566 8 0 0
v_min_non_paid_invoice 5958 5958 0 0.00 0 3752 12 0 0
v_min_non_paid_realizn 7282 7282 0 0.00 0 3520 14 0 0
v_our_firm 10296 10296 0 0.00 0 166 5 0 0
v_reserve_write_off 20434 20434 0 0.00 0 18347 13 0 0
x_lock_depdocs_on_canc_invoice 10502 10502 0 0.00 0 14403 139 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 9085 427 0 0
x_make_qstorno_1000_1200 5628 5628 0 0.00 2 18602 1116 0 0
x_make_qstorno_1200_2000 5698 5698 0 0.00 3 78593 2992 0 0
x_make_qstorno_2100_3300 473700 473700 0 0.00 0 92390 234 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:25 3 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 26216 8192000000 9724990496 10023464960 800457232 8.901 100 84 168 0 828081296 7557888 20066176
08:09:58 17360 8192000000 9762297680 10069102592 835445152 9.254 100 95 190 0 870726656 12145536 23135968
08:11:18 16320 8192000000 9800882592 10117189632 860391392 9.504 100 87 171 1 900507904 20112000 20004512
08:12:46 7818 8192000000 9858103328 10190454784 905172880 9.950 100 84 168 0 965029376 40901008 18955488
08:13:59 3451 8192000000 9905333728 10246893568 953253008 10.423 100 81 160 0 1027378240 57358032 16767200
08:15:05 1963 8192000000 9963922032 10316558336 984500144 10.728 100 95 183 0 1077532096 74146800 18885152
08:16:09 1471 8192000000 10009816416 10369699840 1015600448 11.030 100 94 181 0 1121116240 87751616 17764176
08:17:12 817 8192000000 10017055776 10387288064 1039981856 11.264 100 94 184 1 1136050656 77493648 18575152
08:18:15 1055 8192000000 10042565440 10416283648 1065953856 11.513 100 98 196 0 1160834960 75388224 19492880
08:19:18 1577 8192000000 10060527776 10442723328 1074220192 11.592 100 92 182 0 1172542416 79475888 18846336
08:20:22 1402 8192000000 10061401008 10441129984 1080177232 11.649 100 94 178 2 1182232240 83558464 18496544
08:21:24 923 8192000000 10038510080 10427752448 1085093712 11.696 100 88 168 1 1161662352 60079760 16488880
08:22:26 1028 8192000000 10058600928 10446979072 1085644352 11.701 100 97 187 0 1177836112 73132912 19058848
08:23:28 2397 8192000000 10059643264 10449043456 1090947552 11.752 100 92 178 1 1185202240 75132112 19122576
08:24:32 1166 8192000000 10081148096 10471968768 1098427248 11.823 100 95 185 1 1204820640 86050432 20342960
08:25:34 916 8192000000 10085309456 10476109824 1105046112 11.885 100 95 187 0 1203948000 80059200 18842688
08:26:36 1244 8192000000 10091406464 10482679808 1106230208 11.897 100 96 178 2 1216518304 91636032 18652064
08:27:39 1294 8192000000 10101114000 10494812160 1108997712 11.923 100 96 180 4 1222764624 95119744 18647168
08:28:42 1161 8192000000 10072609680 10463129600 1104762992 11.883 100 99 191 2 1194818720 71652512 18403216
08:29:44 1488 8192000000 10077076272 10471886848 1090990336 11.752 100 93 178 2 1196506656 87871648 17644672
08:30:48 918 8192000000 10055217984 10442027008 1088593152 11.729 100 90 177 2 1172550448 66007440 17949856
08:31:49 1349 8192000000 10041236272 10422947840 1070039984 11.552 100 92 178 1 1159394832 71231808 18123040
08:32:52 4873 8192000000 10025338080 10420314112 1065250272 11.507 100 72 140 0 1153898240 74493296 14154672
08:33:57 1308 8192000000 10017794416 10402115584 1047867344 11.340 100 95 186 0 1137319888 68362272 21090272
08:35:00 1014 8192000000 10034436352 10420072448 1045560576 11.318 100 95 184 0 1154832224 90657152 18614496
08:36:03 1094 8192000000 9984002592 10358390784 1038284416 11.248 100 95 179 0 1106952336 49918032 18749888
08:37:05 4453 8192000000 9978557072 10357006336 1023393472 11.105 100 74 139 3 1096193888 58057504 14742912
08:38:17 1404 8192000000 9984427632 10360979456 1038059408 11.246 100 80 143 2 1108463744 56337776 14066560
08:39:25 1114 8192000000 10034591584 10411859968 1063980816 11.495 100 98 178 2 1156296064 75161648 17153600
08:40:32 25837 8192000000 10052188032 10436517888 1078056608 11.629 100 78 143 1 1176631856 83750672 14824576
08:42:15 3271 8192000000 10076624992 10460717056 1096035616 11.800 100 86 159 1 1191952736 80357200 15559920
08:43:24 3353 8192000000 10125160720 10522738688 1097443072 11.813 100 88 171 2 1242775648 127664848 17667728
08:44:35 4966 8192000000 10147167312 10538795008 1113273040 11.963 100 84 164 2 1265553248 134433504 17846704
08:45:44 2191 8192000000 10166012512 10558414848 1123677360 12.062 100 82 149 0 1279138000 139626304 15834336
08:46:52 1163 8192000000 10187776528 10578137088 1133994448 12.159 100 91 177 2 1299295408 147029232 18271728
08:47:56 3015 8192000000 10222704304 10613317632 1146944864 12.281 100 87 168 2 1333950752 169048272 17957616
08:49:05 1090 8192000000 10252031120 10657910784 1162522464 12.427 100 94 176 1 1362348384 179825712 20000208
08:50:08 1339 8192000000 10281771200 10699411456 1173859616 12.533 100 93 184 0 1390197664 195494320 20843728
08:51:13 2134 8192000000 10277728432 10681634816 1186298800 12.649 100 74 148 0 1389693840 186627056 16767984
08:52:23 1706 8192000000 10335889712 10736775168 1189132560 12.675 100 88 166 1 1439340544 231368816 18839168
08:53:28 1107 8192000000 10377125440 10780344320 1207653568 12.847 100 93 186 0 1486724816 258316832 20754416
08:54:34 728 8192000000 10450553264 10855170048 1207517696 12.846 100 95 185 2 1555644416 326289088 21837632
08:55:38 1182 8192000000 10449755216 10885414912 1217396944 12.938 100 96 185 1 1562789040 322314976 23077120
08:56:42 1184 8192000000 10451284560 10872967168 1203293856 12.807 100 94 179 3 1556713744 333256272 20163616
08:57:50 1138 8192000000 10451981248 10880143360 1195668080 12.736 100 97 193 1 1553162512 335217456 22276976
08:58:54 1190 8192000000 10451143600 10875621376 1189178320 12.676 100 94 185 2 1559843152 348211216 22453616
08:59:58 1298 8192000000 10449707904 10902843392 1176840240 12.561 100 96 185 0 1553630640 354861616 21928784
09:01:02 1243 8192000000 10434172192 10858774528 1183248896 12.620 100 97 189 2 1553600288 347834208 22517184
09:02:06 718 8192000000 10446660960 10884513792 1183970624 12.627 100 87 175 1 1544809168 340308048 20530496
09:03:10 770 8192000000 10421184592 10859966464 1171307776 12.509 100 96 183 1 1520493424 328724688 20460960
09:04:15 935 8192000000 10413825184 10897657856 1167939616 12.478 100 93 178 1 1519248928 329795024 21514288
09:05:20 11455 8192000000 10409378256 10847150080 1168720240 12.485 100 58 113 0 1517949600 335686528 13542832
09:06:33 9703 8192000000 10391962480 10859532288 1155369248 12.360 99 46 92 0 1491588080 324331936 11886896
09:07:56 23027 8192000000 10387855712 10854260736 1149175168 12.302 99 31 62 0 1470133392 312742736 8215488
09:09:21 23949 8192000000 10338678432 10787971072 1148370096 12.294 100 29 56 0 1438410752 282687184 7353472
09:10:47 32691 8192000000 10221441152 10782142464 1112807184 11.959 99 19 38 0 1393174000 274414272 5952544
09:12:21 88721 8192000000 10103611136 10653184000 1122679792 12.052 99 17 34 0 1226356640 100075264 3601584
09:15:02 38746 8192000000 10079707328 10555068416 1106811344 11.902 99 21 42 1 1189630592 78299024 4520224
09:18:25 71015 8192000000 10057116720 10499985408 1096442784 11.804 97 20 40 0 1130257952 29708896 4106272
09:20:40 119650 8192000000 10096698928 10533969920 1122739216 12.053 98 33 57 1 1170732752 42100240 5893296
09:23:42 27207 8192000000 10112267664 10546765824 1136959584 12.187 99 47 82 1 1200296736 54258560 9078592
09:25:18 10268 8192000000 10130189360 10555437056 1140750096 12.223 100 59 113 0 1232290464 79193072 12347296
09:26:50 43248 8192000000 10104032592 10570993664 1127034016 12.093 99 11 22 0 1216771072 87352736 2384320
09:28:34 40697 8192000000 10127201264 10571952128 1137896080 12.196 99 24 47 0 1208377760 65679760 4801920
09:30:15 40227 8192000000 10146061888 10602606592 1134871600 12.167 99 22 43 0 1237954016 97836608 5245808
09:31:55 65505 8192000000 10088193536 10589585408 1140506704 12.220 100 18 36 0 1207988768 63547184 3934880
09:34:01 55312 8192000000 10119442720 10592747520 1118453488 12.012 98 43 78 3 1177578016 49914672 9209856
09:35:57 55034 8192000000 10105566352 10565230592 1108353312 11.917 97 31 62 0 1190309584 74838240 7118032
09:37:57 55357 8192000000 10082220064 10552897536 1124994768 12.074 99 22 44 1 1152709312 23134880 4579664
09:39:57 71634 8192000000 10135480432 10597036032 1140329632 12.219 99 29 54 0 1223446768 77292768 5824368
09:42:26 30800 8192000000 10085817568 10544513024 1130333904 12.125 99 18 30 1 1180521264 46789472 3397888
09:43:59 36182 8192000000 10132687376 10572341248 1093027856 11.771 96 48 94 1 1185383088 82296736 10058496
09:45:36 70194 8192000000 10075368416 10513649664 1117527200 12.004 99 37 74 0 1173729536 49339104 6863232
09:47:48 75313 8192000000 10067352064 10507456512 1077826928 11.627 96 20 39 0 1128796272 46939072 4030272
09:50:06 28598 8192000000 10027821424 10486476800 1099991520 11.838 98 16 31 0 1134998688 31979520 3027648
09:51:46 33102 8192000000 10054090064 10507407360 1106040912 11.895 99 13 24 0 1141778992 33103408 2634672
09:53:19 62977 8192000000 10075619872 10561155072 1117526464 12.004 100 21 42 0 1178940880 57007712 4406704
09:55:24 27772 8192000000 10043544800 10504548352 1093131760 11.772 97 29 52 1 1126194288 28426688 4635840
09:56:55 25861 8192000000 10086318080 10534330368 1104217568 11.878 98 32 62 0 1175506880 64566384 6722928
09:58:22 31924 8192000000 10146729600 10578939904 1126955280 12.093 100 33 65 1 1224496400 90672624 6868496
09:59:56 39478 8192000000 10088344752 10561294336 1110400064 11.936 98 15 30 1 1190743360 76755776 3587520
10:01:41 38154 8192000000 10140030640 10568957952 1120737776 12.034 99 41 79 0 1233182352 104277136 8167440
10:03:27 22934 8192000000 10169399088 10623381504 1119972544 12.027 100 38 76 0 1265029824 137037408 8019872
10:04:52 33548 8192000000 10086512736 10516410368 1120412176 12.031 100 41 82 1 1211207312 82695376 8099760
10:06:26 35864 8192000000 10113397168 10581667840 1109977680 11.932 99 28 56 0 1184289536 68467904 5843952
10:08:21 72817 8192000000 10045120592 10484477952 1117767792 12.006 100 24 49 1 1155303216 31770912 5764512
10:10:38 5514 8192000000 10092235616 10522673152 1130192704 12.123 100 55 108 1 1191382912 50188208 11002000
10:11:45 48813 8192000000 10072284560 10541277184 1112679776 11.958 99 18 35 0 1184208112 67805456 3722880
10:13:35 29631 8192000000 10055456336 10533851136 1130764768 12.129 100 5 8 0 1157040528 25074720 1201040
10:15:04 38621 8192000000 10053610624 10525327360 1130976672 12.131 100 11 20 0 1157776752 24652336 2147744
10:16:46 48684 8192000000 10058644864 10488721408 1102570448 11.862 97 35 53 1 1123362864 16891216 3901200
10:18:36 13767 8192000000 10100299920 10538766336 1139858000 12.214 100 45 85 0 1195501504 46690272 8953232
10:19:55 40640 8192000000 10148718720 10605674496 1156713840 12.372 100 36 70 0 1253016816 88089040 8213936
10:21:37 23050 8192000000 10207640064 10649718784 1168762416 12.485 100 41 82 1 1300804080 122961792 9079872
10:23:02 60836 8192000000 10232374496 10697232384 1143410800 12.248 98 23 46 0 1327131680 177775008 5945872
10:25:23 30793 8192000000 10183164096 10630590464 1100870448 11.846 96 19 38 0 1235236864 129638848 4727568
10:26:54 66476 8192000000 10077430688 10530226176 1098679968 11.825 97 33 66 0 1164125168 58610608 6834592
10:29:08 16030 8192000000 10116911552 10537361408 1130298576 12.124 100 74 148 1 1198753152 53047888 15406688
10:30:27 75727 8192000000 10124739696 10605862912 1136598288 12.184 100 48 94 0 1264335696 117797280 9940128
10:32:54 88661 8192000000 10059571024 10516910080 1095307984 11.793 97 14 26 0 1140598448 42798624 2491840
10:35:23 75362 8192000000 10054885152 10563371008 1105135584 11.886 98 8 12 1 1147718384 40740816 1841984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 05-10-2022 10:39:07 to 05-10-2022 10:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 160 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 05-10-2022 10:39:25 to 05-10-2022 10:39:27.

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          222210
MON$OLDEST_ACTIVE               223529
MON$OLDEST_SNAPSHOT             223529
MON$NEXT_TRANSACTION            223529
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                       2067232
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2855 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2855 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2855 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2855 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Oct  5 10:37:26 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

05.10.22 10:39:28. 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-10-05 10:39:31 No severe PSQL-related problems occured
05.10.22 10:39:32. End of report.