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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15693688 kB
MemAvailable 30601324 kB
Buffers 0 kB
Cached 15986940 kB
SwapCached 3704 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-24 07:47:03
DTS_END                         2021-10-24 07:47:03.5860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55284
TRN_ID                          236006
ATT_ID                          657
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8959.93 26401 1075192
customer order: creation 167.86 410 20143
customer order: refuse 14.08 321 1690
order to supplier: creation 45.18 1408 5421
order to supplier: removal 3.41 128414 409
invoice (draft): creation 45.49 3561 5459
invoice (draft): removal 50.92 1091 6110
invoice accept: apply 40.24 18466 4829
invoice accept: cancel 45.97 914 5516
customer reserve: creation 3757.77 181 450932
customer reserve: removal 4488.52 76 538622
realization accept: apply 81.41 184 9769
realization accept: cancel 90.76 126 10891
payment from customer: creation 59.34 109 7121
payment from customer: removal 5.48 75 658
payment to supplier: creation 48.28 127 5793
payment to supplier: removal 3.73 52 447
service: total inventory turnovers 4.22 3440 507
service: total monetary turnovers 6.31 459 757
service: aggregate perf. data 0.94 17099 113
service: refresh index statistics 0.04 351509 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2021 07:48:17 to 24-10-2021 07:48:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8447
TEST_TIME 2 5541
TEST_TIME 3 4288
TEST_TIME 4 4383
TEST_TIME 5 4250
TEST_TIME 6 4763
TEST_TIME 7 4888
TEST_TIME 8 4821
TEST_TIME 9 4717
TEST_TIME 10 5112
TEST_TIME 11 5093
TEST_TIME 12 5140
TEST_TIME 13 5294
TEST_TIME 14 5318
TEST_TIME 15 5478
TEST_TIME 16 5441
TEST_TIME 17 5690
TEST_TIME 18 6212
TEST_TIME 19 6624
TEST_TIME 20 6835
TEST_TIME 21 7313
TEST_TIME 22 7418
TEST_TIME 23 7524
TEST_TIME 24 8014
TEST_TIME 25 8459
TEST_TIME 26 9795
TEST_TIME 27 10585
TEST_TIME 28 10564
TEST_TIME 29 10583
TEST_TIME 30 10411
TEST_TIME 31 10490
TEST_TIME 32 10370
TEST_TIME 33 10265
TEST_TIME 34 10088
TEST_TIME 35 9933
TEST_TIME 36 9973
TEST_TIME 37 9886
TEST_TIME 38 9845
TEST_TIME 39 9790
TEST_TIME 40 9785
TEST_TIME 41 9814
TEST_TIME 42 9750
TEST_TIME 43 9627
TEST_TIME 44 9629
TEST_TIME 45 9612
TEST_TIME 46 9574
TEST_TIME 47 9622
TEST_TIME 48 9556
TEST_TIME 49 9545
TEST_TIME 50 9689
TEST_TIME 51 9616
TEST_TIME 52 9654
TEST_TIME 53 9812
TEST_TIME 54 9719
TEST_TIME 55 9906
TEST_TIME 56 9846
TEST_TIME 57 9854
TEST_TIME 58 9840
TEST_TIME 59 9870
TEST_TIME 60 9886
TEST_TIME 61 9747
TEST_TIME 62 9719
TEST_TIME 63 9638
TEST_TIME 64 9566
TEST_TIME 65 9587
TEST_TIME 66 9526
TEST_TIME 67 9457
TEST_TIME 68 9411
TEST_TIME 69 9336
TEST_TIME 70 9372
TEST_TIME 71 9336
TEST_TIME 72 9309
TEST_TIME 73 9260
TEST_TIME 74 9301
TEST_TIME 75 9297
TEST_TIME 76 9293
TEST_TIME 77 9315
TEST_TIME 78 9280
TEST_TIME 79 9482
TEST_TIME 80 9456
TEST_TIME 81 9447
TEST_TIME 82 9461
TEST_TIME 83 9503
TEST_TIME 84 9474
TEST_TIME 85 9385
TEST_TIME 86 9356
TEST_TIME 87 9333
TEST_TIME 88 9278
TEST_TIME 89 9283
TEST_TIME 90 9236
TEST_TIME 91 9190
TEST_TIME 92 9146
TEST_TIME 93 9150
TEST_TIME 94 9141
TEST_TIME 95 9130
TEST_TIME 96 9138
TEST_TIME 97 9137
TEST_TIME 98 9137
TEST_TIME 99 9163
TEST_TIME 100 9110
TEST_TIME 101 9135
TEST_TIME 102 9190
TEST_TIME 103 9188
TEST_TIME 104 9240
TEST_TIME 105 9171
TEST_TIME 106 9102
TEST_TIME 107 9057
TEST_TIME 108 9034
TEST_TIME 109 8992
TEST_TIME 110 8963
TEST_TIME 111 8981
TEST_TIME 112 8951
TEST_TIME 113 8924
TEST_TIME 114 9003
TEST_TIME 115 8979
TEST_TIME 116 9005
TEST_TIME 117 9025
TEST_TIME 118 8995
TEST_TIME 119 9025
TEST_TIME 120 8963

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-10-2021 07:48:20 to 24-10-2021 07:48:24.

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 1492203 1492203 0 0.00 0 19075 7 0 0
doc_list_biud 1492202 1492202 0 0.00 0 25723 35 0 0
sp_add_invoice_to_stock 4916 4829 87 1.76 46 256153 18467 0 87
sp_cancel_adding_invoice 5516 5516 0 0.00 6 96053 914 0 0
sp_cancel_client_order 1690 1690 0 0.00 4 9459 322 0 0
sp_cancel_customer_reserve 538622 538622 0 0.00 1 24212 77 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 2483 75 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 2005 52 0 0
sp_cancel_supplier_invoice 6118 6110 8 0.13 3 20602 1091 0 8
sp_cancel_supplier_order 409 409 0 0.00 10 1488878 128415 0 0
sp_cancel_write_off 10891 10891 0 0.00 1 19076 127 0 0
sp_client_order 20143 20143 0 0.00 4 17689 410 0 0
sp_customer_reserve 450932 450932 0 0.00 0 52675 182 0 0
sp_fill_shopping_cart 31015 31015 0 0.00 1 22177 52 0 0
sp_fill_shopping_cart_clo_res 450928 450928 0 0.00 0 17965 16 0 0
sp_get_clo_for_invoice 4824 4824 0 0.00 0 22846 343 0 0
sp_kill_cost_storno 17512 17512 0 0.00 0 19072 59 0 0
sp_kill_qstorno_ret_qs2qd 1094654 1094654 0 0.00 0 24196 42 0 0
sp_kill_qty_storno 577828 577828 0 0.00 0 25723 89 0 0
sp_lock_selected_doc 20310 20310 0 0.00 0 7135 5 0 0
sp_make_cost_storno 27505 27505 0 0.00 0 17132 50 0 0
sp_multiply_rows_for_pdistr 27504 27504 0 0.00 0 9566 32 0 0
sp_multiply_rows_for_qdistr 20143 20143 0 0.00 0 15842 202 0 0
sp_pay_from_customer 7121 7121 0 0.00 4 12776 110 0 0
sp_pay_to_supplier 5793 5793 0 0.00 5 11707 127 0 0
sp_payment_common 12914 12914 0 0.00 1 8268 21 0 0
sp_qd_handle_on_cancel_clo 1690 1690 0 0.00 0 9452 290 0 0
sp_qd_handle_on_invoice_upd_sts 10338 10338 0 0.00 0 25722 369 0 0
sp_qd_handle_on_reserve_upd_sts 9768 9768 0 0.00 0 20851 74 0 0
sp_reserve_write_off 9769 9769 0 0.00 7 20993 184 0 0
sp_supplier_invoice 5459 5459 0 0.00 7 82975 3561 0 0
sp_supplier_order 5421 5421 0 0.00 6 73975 1409 0 0
srv_aggregate_perf_data 113 113 0 0.00 3030 155365 17100 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1190 124736 45632 0 0
srv_find_qd_qs_mism 1028011 1028011 0 0.00 0 13220 6 0 0
srv_make_invnt_saldo 507 507 0 0.00 31 117926 3441 0 0
srv_make_money_saldo 757 757 0 0.00 1 12413 460 0 0
srv_recalc_idx_stat 5 5 0 0.00 230523 548920 351509 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4829 4829 0 0.00 0 9761 150 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 1 3393 126 0 0
t$perf-norm:sp_cancel_client_order 1690 1690 0 0.00 0 1492 10 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 809 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 614 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 7438 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 729 16 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 17478 1465 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 644 2 0 0
t$perf-norm:sp_client_order 20143 20143 0 0.00 0 14423 17 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 712 4 0 0
t$perf-norm:sp_pay_from_customer 7121 7121 0 0.00 0 4909 6 0 0
t$perf-norm:sp_pay_to_supplier 5793 5793 0 0.00 0 6977 7 0 0
t$perf-norm:sp_reserve_write_off 9769 9769 0 0.00 0 7008 9 0 0
t$perf-norm:sp_supplier_invoice 5459 5459 0 0.00 0 5875 17 0 0
t$perf-norm:sp_supplier_order 5421 5421 0 0.00 0 6997 18 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 1058 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 914 30 0 0
t$perf-norm:srv_make_invnt_saldo 507 507 0 0.00 0 5136 21 0 0
t$perf-norm:srv_make_money_saldo 757 757 0 0.00 0 3070 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 31 11 0 0
v_add_invoice_to_stock 9818 9644 174 1.77 0 6523 18 0 174
v_all_customers 37322 37322 0 0.00 0 20 1 0 0
v_all_suppliers 30386 30386 0 0.00 0 22 1 0 0
v_all_wares 40286 40286 0 0.00 0 104 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 2770 10 0 0
v_cancel_client_order 3380 3380 0 0.00 0 2540 5 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 2475 8 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 419 4 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 407 3 0 0
v_cancel_supplier_invoice 776 760 16 2.06 0 1066 16 0 16
v_cancel_supplier_order 818 818 0 0.00 0 859 5 0 0
v_cancel_write_off 1984 1984 0 0.00 0 9011 19 0 0
v_max_id_clo_ord 5420 5420 0 0.00 0 6550 3 0 0
v_max_id_clo_res 998 998 0 0.00 0 2579 11 0 0
v_max_id_ord_sup 5452 5452 0 0.00 0 21835 14 0 0
v_max_non_paid_invoice 1472 1472 0 0.00 0 293 2 0 0
v_max_non_paid_realizn 3604 3604 0 0.00 0 894 3 0 0
v_min_id_clo_ord 5420 5420 0 0.00 0 2777 7 0 0
v_min_id_clo_res 998 998 0 0.00 0 4261 7 0 0
v_min_id_ord_sup 5452 5452 0 0.00 0 5500 8 0 0
v_min_non_paid_invoice 5793 5793 0 0.00 0 8264 16 0 0
v_min_non_paid_realizn 7121 7121 0 0.00 0 6254 14 0 0
v_our_firm 9998 9998 0 0.00 0 15 1 0 0
v_reserve_write_off 19536 19536 0 0.00 0 6199 5 0 0
x_lock_depdocs_on_canc_invoice 11246 11246 0 0.00 0 21985 123 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 19034 425 0 0
x_make_qstorno_1000_1200 5421 5421 0 0.00 2 73956 1330 0 0
x_make_qstorno_1200_2000 5459 5459 0 0.00 3 81405 3344 0 0
x_make_qstorno_2100_3300 450932 450932 0 0.00 0 52674 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2021 07:48:25 to 24-10-2021 07:48:29.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:17:07 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:08 29526 8192000000 9718238352 10017026048 797291424 8.869 100 87 174 0 826653216 8131904 21229888
05:19:43 16072 8192000000 9756665520 10065727488 836676400 9.266 100 88 176 0 870258912 13194960 20387552
05:21:09 13799 8192000000 9796992000 10115538944 869043984 9.590 100 80 160 1 908239072 21064592 18130496
05:22:25 13048 8192000000 9865358256 10195005440 907630192 9.974 100 81 162 0 971796016 46057120 18108704
05:23:41 7404 8192000000 9909609952 10253144064 943500544 10.327 100 68 136 0 1018723872 60406784 14816544
05:24:50 4799 8192000000 9954553248 10308464640 989784032 10.779 100 79 156 2 1068684688 63255120 15645536
05:25:56 3270 8192000000 9974526784 10345185280 1023999584 11.111 100 70 140 0 1102305328 63878896 14426848
05:27:01 2551 8192000000 10013886336 10387824640 1049097440 11.352 100 88 176 2 1129692464 62026656 18568368
05:28:05 2277 8192000000 10049803504 10419695616 1057826880 11.436 100 91 183 0 1160721504 83338032 19556592
05:29:08 7362 8192000000 10012242032 10387591168 1063706096 11.492 100 85 171 1 1122417088 40231680 18479312
05:30:16 5590 8192000000 10014180176 10401599488 1072307632 11.574 100 50 101 0 1124639680 41790864 10541184
05:31:24 5375 8192000000 9991357840 10387341312 1079417008 11.642 100 37 74 0 1115737504 28969296 7351200
05:32:33 38340 8192000000 10005507008 10419929088 1085559984 11.700 100 41 81 1 1115198032 21077744 8560304
05:34:16 6445 8192000000 10003270496 10406723584 1096211568 11.802 100 34 68 1 1124629280 20788048 7629664
05:35:23 4704 8192000000 10030732848 10420191232 1099253872 11.831 100 36 65 1 1127033088 21155184 6624032
05:36:28 4134 8192000000 10021285792 10417278976 1097060768 11.810 100 39 78 0 1126524784 20798160 8665856
05:37:36 30801 8192000000 9996414880 10413506560 1065385552 11.508 97 24 48 0 1098848480 27789808 5673120
05:39:09 9098 8192000000 9933068576 10313420800 983627632 10.720 94 32 65 2 1013910864 23066720 7216512
05:40:26 9183 8192000000 9942682672 10333450240 983801056 10.721 95 22 44 0 1020144352 31698112 4645184
05:41:36 12884 8192000000 9931033392 10313867264 1006818592 10.945 99 31 61 0 1031794448 18062544 6913312
05:42:50 26455 8192000000 9938563024 10333872128 1011840608 10.993 98 22 44 1 1041014496 24820560 4353328
05:44:19 2809 8192000000 9982726880 10362900480 1052235904 11.382 100 49 99 1 1085265776 22660480 10369392
05:45:23 4186 8192000000 9983061728 10368909312 1057450288 11.432 100 47 93 0 1091053056 23911648 9691120
05:46:28 8177 8192000000 9992607152 10385113088 1065859360 11.513 100 31 62 2 1098190240 25863568 6467312
05:47:37 75151 8192000000 9988568672 10401316864 1073769152 11.588 100 27 52 3 1106563648 26130144 6664352
05:49:58 51718 8192000000 10047591552 10451947520 1093830368 11.779 100 41 82 1 1126934832 24300640 8803824
05:51:55 4340 8192000000 10097060752 10494550016 1116356720 11.993 100 59 115 1 1208567856 80190736 12020400
05:53:02 20426 8192000000 10169970944 10574278656 1135716432 12.175 100 45 90 1 1278880512 133348544 9815536
05:54:36 28082 8192000000 10203303888 10609664000 1141401920 12.229 99 45 90 2 1302432016 150945296 10084800
05:56:10 7296 8192000000 10256743072 10667548672 1166860320 12.467 100 46 91 1 1364181936 187113360 10208256
05:57:21 8853 8192000000 10300109728 10722942976 1177975600 12.571 100 50 98 1 1399355696 210263344 11116752
05:58:33 3811 8192000000 10348841344 10757709824 1198936816 12.766 100 76 149 0 1458735296 243055168 16743312
05:59:42 6495 8192000000 10374422768 10785951744 1186793408 12.654 99 63 124 0 1466672128 266162720 13716000
06:00:56 2579 8192000000 10424331376 10833625088 1200820560 12.784 100 84 169 2 1522062112 301915952 19325600
06:02:07 3094 8192000000 10451119360 10861838336 1205279232 12.825 100 81 160 0 1548568272 324750656 18538384
06:03:16 3210 8192000000 10489545664 10897174528 1210347536 12.872 100 82 162 2 1587881488 357810608 19723344
06:04:22 3246 8192000000 10505730944 10911019008 1180208928 12.592 99 84 166 0 1598434672 397696672 20529072
06:05:32 1189 8192000000 10540500864 10943922176 1184222176 12.630 100 98 196 1 1635683408 429466944 21994288
06:06:36 4097 8192000000 10520398016 10958647296 1188996608 12.674 100 67 134 1 1620792720 415941504 15854608
06:07:42 2154 8192000000 10502571040 10912165888 1148440592 12.295 98 69 134 2 1591708320 426510752 16756976
06:08:48 12795 8192000000 10521612688 10955628544 1147046640 12.282 98 47 94 1 1614859728 456056736 11756352
06:10:17 19646 8192000000 10560584992 11022094336 1151765760 12.326 99 41 82 0 1648965568 486292880 10906928
06:11:47 24989 8192000000 10507484896 10997772288 1133399424 12.153 99 28 56 3 1611796800 470049088 8348288
06:13:53 36679 8192000000 10057811168 10530123776 1103009216 11.866 97 12 24 1 1234416768 128458320 2949232
06:15:32 60806 8192000000 10040041456 10478874624 1047226368 11.334 93 17 34 0 1108049696 56974256 3849072
06:17:48 74234 8192000000 10040787392 10514759680 1064497248 11.500 96 20 40 2 1127924528 58836704 4590576
06:20:04 111548 8192000000 10075211696 10522427392 1103799488 11.874 100 22 44 0 1162455344 54188192 4467664
06:23:10 73208 8192000000 10058131584 10483548160 1095331664 11.793 98 22 44 0 1133389008 33397744 4659600
06:25:25 20865 8192000000 10083476880 10541711360 1117934352 12.007 100 31 60 2 1185476848 60693328 6849168
06:26:50 12267 8192000000 10111981616 10548158464 1116766336 11.996 100 48 95 2 1204774640 77856112 10152192
06:28:03 54395 8192000000 10075635424 10541903872 1105546864 11.890 98 18 36 0 1189162352 79660256 3955232
06:29:59 47078 8192000000 10112692256 10574651392 1120389040 12.031 99 14 28 1 1189778368 66438496 2950832
06:31:54 64038 8192000000 10090905216 10595897344 1131855008 12.139 100 13 26 1 1208475280 73569776 3050496
06:34:12 46505 8192000000 10074844000 10521214976 1143219248 12.246 100 33 64 1 1211516432 61360208 6936976
06:35:59 49185 8192000000 10132178384 10578624512 1161939856 12.421 100 15 30 0 1233924816 68685904 3299056
06:37:49 36437 8192000000 10117216800 10554953728 1142881520 12.243 98 30 59 1 1169995968 21502272 5612176
06:39:27 49292 8192000000 10133001904 10648526848 1145615040 12.268 99 12 24 1 1255004224 106901296 2487888
06:41:18 63751 8192000000 10153673072 10611122176 1145812816 12.270 99 12 24 0 1249851024 101408288 2629920
06:43:24 29188 8192000000 10086710112 10517331968 1110711968 11.939 97 33 66 1 1146217760 28898848 6606944
06:44:54 32984 8192000000 10106106848 10567155712 1146967552 12.281 100 40 80 2 1200668400 44785296 8915552
06:46:28 67324 8192000000 10065797744 10578571264 1101916288 11.856 97 20 39 1 1168864960 62673872 4274800
06:48:39 111273 8192000000 10070980992 10525675520 1089006128 11.733 96 31 62 0 1120164336 24928016 6230192
06:51:31 65804 8192000000 10073516912 10566057984 1115190912 11.982 98 22 44 0 1201710112 81610032 4909168
06:54:45 58840 8192000000 10099509712 10569789440 1134916080 12.168 99 21 42 2 1202574928 62745680 4913168
06:56:44 26460 8192000000 10125281088 10571079680 1132398352 12.144 99 25 49 1 1219383600 82083056 4902192
06:58:10 91877 8192000000 10068661440 10545561600 1104257328 11.878 97 16 32 0 1147744976 40237376 3250272
07:00:43 68492 8192000000 10073371760 10507661312 1039895312 11.264 92 18 36 0 1106922928 62893696 4133920
07:03:11 74446 8192000000 10109709920 10580213760 1094369312 11.784 98 24 48 1 1201116224 101542656 5204256
07:05:26 64884 8192000000 10038983840 10510778368 1065646784 11.510 95 12 24 0 1181427648 113136320 2644544
07:07:31 16775 8192000000 10079350576 10493120512 1100026112 11.838 99 50 99 0 1155150208 45473616 9650480
07:08:50 12711 8192000000 10100512032 10543648768 1122820912 12.054 100 52 104 0 1208879008 75604096 10454000
07:10:05 11835 8192000000 10122024608 10541584384 1133769984 12.157 100 46 92 0 1206472688 63670448 9032256
07:11:19 17733 8192000000 10073281984 10496270336 1132972416 12.149 100 44 88 0 1169462848 27416912 9073520
07:12:37 101245 8192000000 10082295776 10534813696 1119121008 12.019 99 44 88 0 1201595584 73440496 9034080
07:15:20 77539 8192000000 10095820288 10562449408 1128503440 12.107 99 16 32 0 1201104576 69190416 3410720
07:18:28 59885 8192000000 10061636128 10542325760 1110192608 11.934 98 9 18 0 1147777488 35917600 1667280
07:20:29 38430 8192000000 10049577680 10491523072 1111383456 11.946 98 25 50 0 1144166000 27459872 5322672
07:22:07 55620 8192000000 10092719120 10525204480 1121751488 12.044 99 13 26 0 1162830080 38283280 2795312
07:24:04 74900 8192000000 10081975776 10562695168 1121037856 12.037 98 18 36 0 1167356496 42541776 3776864
07:26:21 78963 8192000000 10092715088 10527309824 1126229840 12.086 98 19 38 0 1166213552 36085472 3898240
07:28:42 124736 8192000000 10077868336 10578550784 1096001712 11.800 96 16 32 1 1171322416 72272896 3047808
07:31:48 106468 8192000000 10046009392 10512859136 1093007552 11.771 97 11 22 0 1137180224 41949360 2223312
07:34:35 70121 8192000000 10092941136 10552733696 1141661552 12.231 100 21 42 0 1202672224 56797984 4212688
07:36:50 42834 8192000000 10114036624 10585485312 1134797264 12.167 99 10 20 0 1201286608 64267168 2222176
07:38:34 78920 8192000000 10105678064 10552901632 1124079696 12.066 98 13 26 0 1187233728 60316224 2837808
07:40:57 45442 8192000000 10055215632 10499280896 1077072080 11.620 94 18 36 0 1147624656 66852400 3700176
07:42:54 35547 8192000000 10127175808 10557116416 1140629648 12.221 100 44 88 1 1209963856 59974336 9359872
07:44:36 95677 8192000000 10161053776 10612568064 1085415648 11.699 95 16 32 1 1179250064 89804320 4030096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-10-2021 07:48:30 to 24-10-2021 07:48:46.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2021 07:48:46 to 24-10-2021 07:48:48.

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          233591
MON$OLDEST_ACTIVE               236121
MON$OLDEST_SNAPSHOT             236121
MON$NEXT_TRANSACTION            236121
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                       2052384
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2642 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2642 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2642 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2642 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sun Oct 24 07:47:04 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sun Oct 24 07:47:08 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sun Oct 24 07:47:15 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sun Oct 24 07:47:15 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

24.10.21 07:48:49. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-10-24 07:48:52 No severe PSQL-related problems occured
24.10.21 07:48:53. End of report.