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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-10 10:37:18
DTS_END                         2022-07-10 10:37:18.3430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36322
TRN_ID                          223741
ATT_ID                          580
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: 8590.12 27891 1030814
customer order: creation 168.98 462 20277
customer order: refuse 14.04 410 1685
order to supplier: creation 45.78 1368 5493
order to supplier: removal 3.13 151604 375
invoice (draft): creation 46.10 3481 5532
invoice (draft): removal 46.49 912 5579
invoice accept: apply 41.55 26690 4986
invoice accept: cancel 41.51 1201 4981
customer reserve: creation 3838.28 256 460593
customer reserve: removal 4057.49 92 486899
realization accept: apply 82.39 272 9887
realization accept: cancel 76.17 177 9140
payment from customer: creation 58.47 170 7016
payment from customer: removal 5.83 117 700
payment to supplier: creation 47.33 211 5679
payment to supplier: removal 3.45 119 414
service: total inventory turnovers 4.24 3461 509
service: total monetary turnovers 7.93 354 952
service: aggregate perf. data 0.93 17318 112
service: refresh index statistics 0.04 349143 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-07-2022 10:38:07 to 10-07-2022 10:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8650
TEST_TIME 2 6288
TEST_TIME 3 6124
TEST_TIME 4 6375
TEST_TIME 5 6800
TEST_TIME 6 6526
TEST_TIME 7 6092
TEST_TIME 8 6001
TEST_TIME 9 6270
TEST_TIME 10 6728
TEST_TIME 11 6982
TEST_TIME 12 7069
TEST_TIME 13 7495
TEST_TIME 14 7611
TEST_TIME 15 7824
TEST_TIME 16 7912
TEST_TIME 17 8019
TEST_TIME 18 7897
TEST_TIME 19 7900
TEST_TIME 20 7976
TEST_TIME 21 7954
TEST_TIME 22 8093
TEST_TIME 23 8302
TEST_TIME 24 8297
TEST_TIME 25 8656
TEST_TIME 26 9135
TEST_TIME 27 9593
TEST_TIME 28 9555
TEST_TIME 29 10165
TEST_TIME 30 10750
TEST_TIME 31 10648
TEST_TIME 32 10551
TEST_TIME 33 10316
TEST_TIME 34 10242
TEST_TIME 35 10108
TEST_TIME 36 9925
TEST_TIME 37 9858
TEST_TIME 38 9746
TEST_TIME 39 9620
TEST_TIME 40 9552
TEST_TIME 41 9460
TEST_TIME 42 9455
TEST_TIME 43 9422
TEST_TIME 44 9360
TEST_TIME 45 9224
TEST_TIME 46 9087
TEST_TIME 47 9033
TEST_TIME 48 8978
TEST_TIME 49 9027
TEST_TIME 50 8938
TEST_TIME 51 9008
TEST_TIME 52 9047
TEST_TIME 53 9004
TEST_TIME 54 8968
TEST_TIME 55 8954
TEST_TIME 56 9026
TEST_TIME 57 8995
TEST_TIME 58 9140
TEST_TIME 59 9228
TEST_TIME 60 9216
TEST_TIME 61 9134
TEST_TIME 62 9129
TEST_TIME 63 9055
TEST_TIME 64 9051
TEST_TIME 65 8985
TEST_TIME 66 8970
TEST_TIME 67 8903
TEST_TIME 68 8888
TEST_TIME 69 8857
TEST_TIME 70 8759
TEST_TIME 71 8745
TEST_TIME 72 8764
TEST_TIME 73 8797
TEST_TIME 74 8791
TEST_TIME 75 8819
TEST_TIME 76 8865
TEST_TIME 77 8857
TEST_TIME 78 8879
TEST_TIME 79 8860
TEST_TIME 80 8918
TEST_TIME 81 8903
TEST_TIME 82 8977
TEST_TIME 83 8980
TEST_TIME 84 8955
TEST_TIME 85 8946
TEST_TIME 86 8882
TEST_TIME 87 8840
TEST_TIME 88 8817
TEST_TIME 89 8775
TEST_TIME 90 8749
TEST_TIME 91 8679
TEST_TIME 92 8675
TEST_TIME 93 8652
TEST_TIME 94 8629
TEST_TIME 95 8666
TEST_TIME 96 8648
TEST_TIME 97 8637
TEST_TIME 98 8612
TEST_TIME 99 8676
TEST_TIME 100 8675
TEST_TIME 101 8711
TEST_TIME 102 8729
TEST_TIME 103 8690
TEST_TIME 104 8684
TEST_TIME 105 8780
TEST_TIME 106 8782
TEST_TIME 107 8738
TEST_TIME 108 8712
TEST_TIME 109 8668
TEST_TIME 110 8604
TEST_TIME 111 8573
TEST_TIME 112 8566
TEST_TIME 113 8659
TEST_TIME 114 8653
TEST_TIME 115 8658
TEST_TIME 116 8628
TEST_TIME 117 8655
TEST_TIME 118 8659
TEST_TIME 119 8671
TEST_TIME 120 8621

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-07-2022 10:38:10 to 10-07-2022 10:38:13.

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 1452096 1452096 0 0.00 0 42385 13 0 0
doc_list_biud 1452087 1452087 0 0.00 0 60163 37 0 0
sp_add_invoice_to_stock 5111 4986 125 2.44 33 255220 26691 0 125
sp_cancel_adding_invoice 4981 4981 0 0.00 7 73053 1201 0 0
sp_cancel_client_order 1685 1685 0 0.00 6 16004 410 0 0
sp_cancel_customer_reserve 486899 486899 0 0.00 1 35288 92 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 4229 117 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 3685 120 0 0
sp_cancel_supplier_invoice 5590 5579 11 0.19 3 60285 912 0 11
sp_cancel_supplier_order 375 375 0 0.00 8 1387979 151604 0 0
sp_cancel_write_off 9140 9140 0 0.00 1 10488 177 0 0
sp_client_order 20277 20277 0 0.00 5 42906 462 0 0
sp_customer_reserve 460593 460593 0 0.00 0 110842 256 0 0
sp_fill_shopping_cart 31293 31293 0 0.00 1 19488 89 0 0
sp_fill_shopping_cart_clo_res 460557 460557 0 0.00 0 17290 20 0 0
sp_get_clo_for_invoice 4940 4940 0 0.00 0 32609 346 0 0
sp_kill_cost_storno 15235 15235 0 0.00 0 10484 73 0 0
sp_kill_qstorno_ret_qs2qd 988892 988892 0 0.00 0 60156 41 0 0
sp_kill_qty_storno 523483 523483 0 0.00 0 60160 94 0 0
sp_lock_selected_doc 20523 20523 0 0.00 0 5546 7 0 0
sp_make_cost_storno 27521 27521 0 0.00 0 42366 83 0 0
sp_multiply_rows_for_pdistr 27521 27521 0 0.00 0 23142 41 0 0
sp_multiply_rows_for_qdistr 20277 20277 0 0.00 0 42817 183 0 0
sp_pay_from_customer 7016 7016 0 0.00 6 16801 170 0 0
sp_pay_to_supplier 5679 5679 0 0.00 5 23254 211 0 0
sp_payment_common 12695 12695 0 0.00 2 14071 47 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 1 15994 342 0 0
sp_qd_handle_on_invoice_upd_sts 9919 9919 0 0.00 1 30748 501 0 0
sp_qd_handle_on_reserve_upd_sts 9886 9886 0 0.00 0 12167 65 0 0
sp_reserve_write_off 9887 9887 0 0.00 8 17994 273 0 0
sp_supplier_invoice 5532 5532 0 0.00 10 96671 3481 0 0
sp_supplier_order 5493 5493 0 0.00 7 26068 1369 0 0
srv_aggregate_perf_data 112 112 0 0.00 2512 187137 17319 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 711 147079 41571 0 0
srv_find_qd_qs_mism 980897 980897 0 0.00 0 22507 10 0 0
srv_make_invnt_saldo 509 509 0 0.00 39 103470 3462 0 0
srv_make_money_saldo 952 952 0 0.00 1 9789 354 0 0
srv_recalc_idx_stat 5 5 0 0.00 165741 514660 349144 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 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 4995 4995 0 0.00 1 52238 900 0 0
t$perf-norm:sp_cancel_adding_invoice 371 371 0 0.00 2 9634 565 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 3418 28 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 1704 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 1104 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 760 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 445 445 0 0.00 0 716 34 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 96477 6186 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 1824 20 0 0
t$perf-norm:sp_client_order 20278 20278 0 0.00 0 7563 36 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 1798 15 0 0
t$perf-norm:sp_pay_from_customer 7016 7016 0 0.00 0 6879 19 0 0
t$perf-norm:sp_pay_to_supplier 5679 5679 0 0.00 0 42331 28 0 0
t$perf-norm:sp_reserve_write_off 9887 9887 0 0.00 0 7363 23 0 0
t$perf-norm:sp_supplier_invoice 5532 5532 0 0.00 0 5434 29 0 0
t$perf-norm:sp_supplier_order 5493 5493 0 0.00 0 3682 33 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 576 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 36 3 0 0
t$perf-norm:srv_make_invnt_saldo 509 509 0 0.00 0 1047 14 0 0
t$perf-norm:srv_make_money_saldo 952 952 0 0.00 0 224 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 254 101 0 0
v_add_invoice_to_stock 10118 9868 250 2.47 0 3130 24 0 250
v_all_customers 37042 37042 0 0.00 0 78 4 0 0
v_all_suppliers 29974 29974 0 0.00 0 71 4 0 0
v_all_wares 40550 40550 0 0.00 0 70 4 0 0
v_cancel_adding_invoice 742 742 0 0.00 0 272 15 0 0
v_cancel_client_order 3370 3370 0 0.00 0 2301 10 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 479 10 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 5072 16 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 3647 17 0 0
v_cancel_supplier_invoice 912 890 22 2.41 0 2950 30 0 22
v_cancel_supplier_order 750 750 0 0.00 0 2032 14 0 0
v_cancel_write_off 2022 2022 0 0.00 0 2695 24 0 0
v_max_id_clo_ord 5492 5492 0 0.00 0 365 5 0 0
v_max_id_clo_res 960 960 0 0.00 0 2687 15 0 0
v_max_id_ord_sup 5525 5525 0 0.00 0 5597 9 0 0
v_max_non_paid_invoice 1709 1709 0 0.00 0 185 7 0 0
v_max_non_paid_realizn 3803 3803 0 0.00 0 2968 8 0 0
v_min_id_clo_ord 5492 5492 0 0.00 0 3531 10 0 0
v_min_id_clo_res 960 960 0 0.00 0 2881 18 0 0
v_min_id_ord_sup 5525 5525 0 0.00 0 5019 12 0 0
v_min_non_paid_invoice 5679 5679 0 0.00 0 14062 20 0 0
v_min_non_paid_realizn 7016 7016 0 0.00 0 7443 19 0 0
v_our_firm 9934 9934 0 0.00 0 74 4 0 0
v_reserve_write_off 19772 19772 0 0.00 0 5048 10 0 0
x_lock_depdocs_on_canc_invoice 10115 10115 0 0.00 0 16599 135 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 9235 384 0 0
x_make_qstorno_1000_1200 5493 5493 0 0.00 2 26057 1197 0 0
x_make_qstorno_1200_2000 5532 5532 0 0.00 4 96478 3211 0 0
x_make_qstorno_2100_3300 460591 460591 0 0.00 0 110824 225 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-07-2022 10:38:14 to 10-07-2022 10:38:19.

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:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 35038 8192000000 9739287728 10039074816 815261296 9.051 100 90 180 0 844335760 7665808 21408656
08:10:03 25664 8192000000 9773833504 10081603584 844452512 9.344 100 89 178 0 879337648 13829152 21055984
08:11:35 11899 8192000000 9806556672 10128658432 872676816 9.627 100 83 166 0 914131936 22744560 18710560
08:12:49 5913 8192000000 9876832864 10211209216 916813488 10.065 100 78 153 0 984182640 50970064 16399088
08:13:58 5211 8192000000 9918321360 10263449600 950482272 10.396 100 73 146 0 1028106784 62210176 15414336
08:15:06 1620 8192000000 9960676112 10313596928 992925408 10.810 100 94 177 1 1074753936 64779184 17049344
08:16:11 737 8192000000 10009310224 10370031616 1013134400 11.006 100 96 189 2 1126465456 95226512 18104544
08:17:14 1200 8192000000 10004327504 10369695744 1033320448 11.200 100 91 179 0 1125326480 74301104 17704928
08:18:16 1043 8192000000 10020730624 10390835200 1053160480 11.391 100 92 179 1 1136595696 66175376 17259840
08:19:19 4293 8192000000 10032287600 10408488960 1059468416 11.451 100 91 178 0 1146104512 68562800 18073296
08:20:24 3811 8192000000 10020928512 10410012672 1064272752 11.497 100 73 141 1 1144828384 65850672 14704960
08:21:29 1079 8192000000 10053285920 10434109440 1076329888 11.612 100 96 184 3 1174271872 79298064 18643920
08:22:31 1006 8192000000 10039523888 10426892288 1076855600 11.617 100 87 168 1 1161416464 67578688 16982176
08:23:37 1522 8192000000 10044147088 10433175552 1083679968 11.683 100 84 161 4 1166006048 65201168 17124912
08:24:39 1059 8192000000 10042462288 10429497344 1089923152 11.742 100 93 179 2 1164326544 56842496 17560896
08:25:42 986 8192000000 10075365184 10461650944 1098946656 11.828 100 94 182 1 1195678976 77905456 18826864
08:26:44 7049 8192000000 10046506928 10441109504 1094950304 11.790 100 68 130 1 1178730304 71123328 12656672
08:27:53 1101 8192000000 10047343152 10434998272 1091602144 11.758 100 90 167 0 1166423360 58000608 16820608
08:28:55 2546 8192000000 10061400224 10457837568 1083397776 11.680 100 79 151 0 1181994128 83941840 14654512
08:30:06 1756 8192000000 10028782240 10412601344 1083803936 11.684 100 90 161 0 1145213472 44961680 16447856
08:31:08 1037 8192000000 10021054800 10400829440 1070164832 11.554 100 87 164 0 1144355264 57125376 17065056
08:32:11 858 8192000000 10022173216 10400755712 1061343840 11.469 100 91 176 0 1139690880 59910144 18436896
08:33:12 1262 8192000000 9990925232 10363568128 1039686768 11.262 100 96 177 1 1115671472 57103184 18881520
08:34:14 981 8192000000 10007455488 10385100800 1038499408 11.250 100 91 170 3 1131661968 75209232 17953328
08:35:16 1200 8192000000 9988202864 10364690432 1032724080 11.195 100 86 164 5 1110820576 60936816 17159680
08:36:19 1041 8192000000 9974860240 10348949504 1033569872 11.203 100 87 163 2 1102233824 51119344 17544608
08:37:21 11819 8192000000 10001592352 10387218432 1050386192 11.364 100 62 117 0 1115888336 54225136 11277008
08:38:40 8643 8192000000 10007573088 10384781312 1057197440 11.430 99 74 139 0 1124188288 52654976 14335872
08:39:55 3543 8192000000 10045506480 10425143296 1085122688 11.696 100 78 146 2 1163288080 63405216 14760176
08:41:02 12946 8192000000 10082504784 10477064192 1096690048 11.806 100 66 127 2 1204491072 94027888 13773136
08:42:18 1086 8192000000 10111069504 10500919296 1109311504 11.926 100 96 183 1 1232643056 103214784 20116768
08:43:22 4657 8192000000 10128236144 10523455488 1118709536 12.015 100 84 161 2 1245528752 107203760 19615456
08:44:38 2038 8192000000 10144109472 10543697920 1127397616 12.097 100 64 118 1 1265342896 125249856 12695424
08:45:49 2703 8192000000 10178094976 10584125440 1132225824 12.142 100 80 146 2 1290856800 143671808 14959168
08:46:55 1508 8192000000 10231750368 10640437248 1135088912 12.169 99 87 169 0 1345499088 192597120 17813056
08:48:00 1140 8192000000 10258039232 10659057664 1158242112 12.387 100 93 182 2 1368576912 191860896 18473904
08:49:03 2349 8192000000 10296508480 10702249984 1165988448 12.459 100 90 175 0 1407525200 222023360 19513392
08:50:09 1078 8192000000 10284581264 10728558592 1172084912 12.516 100 97 188 0 1390508672 197106016 21317744
08:51:14 3189 8192000000 10300960384 10712264704 1170338912 12.500 100 81 159 0 1404841344 217033952 17468480
08:52:22 2271 8192000000 10309135472 10717601792 1174686272 12.541 100 85 156 1 1413640208 222120704 16833232
08:53:29 699 8192000000 10312653040 10715176960 1183597968 12.624 100 96 184 2 1417480640 212731008 21151664
08:54:33 1858 8192000000 10345217744 10770485248 1181666576 12.606 100 87 173 0 1455793888 254379984 19747328
08:55:39 1138 8192000000 10383301712 10786615296 1188225280 12.667 100 95 187 0 1488868848 279003376 21640192
08:56:44 849 8192000000 10405259248 10817363968 1184158928 12.629 100 95 174 0 1510628736 306475008 19994800
08:57:48 834 8192000000 10467462352 10877689856 1194341008 12.724 100 95 184 1 1570713248 354960688 21411552
08:58:51 1571 8192000000 10506898640 10925953024 1191348352 12.696 100 95 183 2 1616910368 403556512 22005504
08:59:56 812 8192000000 10511386048 10924433408 1194641328 12.727 100 99 194 0 1613872176 395968400 23262448
09:00:58 5949 8192000000 10475588784 10918944768 1172706928 12.522 100 68 131 1 1582919952 393919696 16293328
09:02:08 1380 8192000000 10483021120 10917543936 1176112208 12.554 100 70 140 1 1588603968 395081456 17410304
09:03:11 19337 8192000000 10398837824 10877198336 1169367872 12.491 100 41 82 0 1536406848 355960624 11078352
09:04:57 30738 8192000000 10309210384 10811060224 1152492080 12.333 100 22 44 0 1434459712 276741984 5225648
09:06:39 53608 8192000000 10175000432 10639908864 1131715984 12.138 99 14 26 1 1338318288 202738704 3863600
09:08:33 24161 8192000000 10086924576 10514571264 1121613248 12.042 99 40 63 4 1179541184 52011808 5916128
09:09:57 37977 8192000000 10104949920 10563506176 1128593648 12.108 100 65 108 2 1200504016 60741040 11169328
09:11:37 35112 8192000000 10049424384 10496733184 1118005584 12.008 99 29 57 0 1144465952 20784944 5675424
09:13:12 40595 8192000000 10060895536 10512871424 1119795296 12.025 99 26 52 0 1150241760 24625344 5821120
09:14:54 56828 8192000000 10053489648 10503544832 1091638656 11.758 97 13 26 1 1136338448 42057360 2642432
09:16:52 80384 8192000000 10111279216 10559442944 1137278608 12.190 100 23 46 0 1208769056 66378736 5111712
09:19:13 107975 8192000000 10078680528 10545504256 1123552304 12.061 99 22 41 0 1214733360 85496960 5684096
09:22:02 109646 8192000000 10110697136 10575519744 1141710144 12.232 100 28 54 0 1206929136 59087264 6131728
09:24:56 14223 8192000000 10112405200 10549903360 1142329216 12.237 100 50 90 2 1217496400 65662448 9504736
09:26:30 61698 8192000000 10108979520 10567299072 1120690976 12.034 98 19 37 2 1207486512 82857552 3937984
09:28:36 80061 8192000000 10098759728 10535874560 1108757968 11.921 97 19 38 2 1159369168 46220336 4390864
09:31:00 30954 8192000000 10131719488 10572902400 1103602960 11.872 96 30 55 1 1195308560 86029488 5676112
09:32:34 111287 8192000000 10150435408 10592391168 1087900592 11.723 95 23 46 0 1183576672 91170912 4505168
09:35:28 73929 8192000000 10111400464 10631323648 1136894800 12.186 100 17 35 0 1247335984 106486944 3954240
09:37:42 34581 8192000000 10113847792 10537066496 1129490880 12.117 99 49 93 1 1186415472 47625968 9298624
09:39:18 147078 8192000000 10068185392 10533888000 1120813312 12.035 98 32 64 0 1197071024 69615328 6642384
09:42:52 47588 8192000000 10057426528 10514190336 1119602640 12.023 99 22 42 0 1166369408 42699008 4067760
09:44:45 65963 8192000000 10086901280 10569637888 1131314512 12.134 99 16 32 0 1195184048 60116560 3752976
09:46:53 84067 8192000000 10097360448 10590408704 1138438448 12.201 100 9 18 0 1213478336 72631104 2408784
09:49:17 51572 8192000000 10115953264 10586763264 1141819328 12.233 100 20 40 0 1231151200 84413856 4918016
09:51:14 86928 8192000000 10070818224 10523508736 1089326640 11.736 96 17 34 0 1165585472 72554016 3704816
09:53:43 44877 8192000000 10081142176 10540470272 1103759264 11.873 98 26 52 0 1151693440 42459104 5475072
09:55:34 15537 8192000000 10098728048 10529886208 1113027456 11.961 98 43 86 0 1169933728 48304320 8601952
09:57:10 19363 8192000000 10078334320 10525241344 1079005968 11.638 97 45 89 1 1158388304 70597344 8784992
09:58:33 22628 8192000000 10106197664 10523959296 1108702496 11.920 100 51 102 0 1195790544 76838496 10249552
09:59:56 38849 8192000000 10093688496 10517229568 1077656960 11.625 97 42 83 0 1151512128 65644160 8211008
10:01:36 49293 8192000000 10044077808 10470666240 1068232576 11.535 96 33 66 0 1148536496 73234544 7069376
10:03:31 58828 8192000000 10033392272 10467332096 1095092560 11.791 99 41 75 3 1139552720 37576816 6883344
10:05:30 54496 8192000000 10048875216 10525061120 1102442192 11.861 99 26 50 0 1174222944 66504896 5275856
10:07:40 101186 8192000000 10087389664 10541522944 1094576672 11.786 98 12 23 0 1149265296 52494096 2194528
10:10:50 85777 8192000000 10077676400 10525376512 1086911920 11.713 97 19 36 0 1131604784 40881248 3811616
10:13:16 39965 8192000000 10069379248 10524717056 1123537760 12.060 100 15 30 0 1153952336 27447200 2967376
10:15:04 31933 8192000000 10097451376 10535845888 1115348832 11.983 99 33 66 0 1182135056 60297392 6488832
10:16:38 59320 8192000000 10111604128 10602754048 1133126480 12.151 100 36 72 0 1220733056 79481072 8125504
10:18:43 65402 8192000000 10065140048 10539393024 1068537056 11.538 94 20 40 0 1132519264 59808512 4173696
10:20:58 69412 8192000000 10099753520 10602979328 1106881024 11.903 98 31 62 0 1210523664 97362960 6279680
10:23:24 129866 8192000000 10121143920 10605248512 1145358912 12.266 100 27 52 1 1210852528 59865600 5628016
10:26:34 61843 8192000000 10102010688 10555674624 1141332608 12.228 99 13 22 0 1199014384 55218624 2463152
10:28:36 113265 8192000000 10150136112 10632019968 1103629040 11.872 96 14 28 0 1221731392 115029568 3072784
10:31:44 75471 8192000000 10074939632 10521563136 1123869600 12.064 98 14 26 0 1175458032 48445488 3142944
10:34:10 57011 8192000000 10110706128 10564444160 1147871104 12.290 99 26 51 0 1227061264 74131088 5059072
10:36:07 71835 8192000000 10017895088 10464165888 1043446176 11.298 90 23 46 0 1091923392 43992592 4484624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-07-2022 10:38:21 to 10-07-2022 10:38:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 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 10-07-2022 10:38:37 to 10-07-2022 10:38:39.

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          221137
MON$OLDEST_ACTIVE               224072
MON$OLDEST_SNAPSHOT             224072
MON$NEXT_TRANSACTION            224072
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                       2012800
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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 ---
:202: 
:203: mscs-test-161.budzdorov.ru    Sun Jul 10 10:37:21 2022
:204:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:205: 
:206: 
:207: mscs-test-161.budzdorov.ru    Sun Jul 10 10:37:25 2022
:208:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:209: 
--- end of diff output ---

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

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