Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.07.2022 19:40
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 16033024 kB
MemAvailable 31098872 kB
Buffers 0 kB
Cached 15710768 kB
SwapCached 2500 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 3.0.11
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-20 19:38:26
DTS_END                         2022-07-20 19:38:26.0760
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(57:17)
IP                              ::1/37578
TRN_ID                          242289
ATT_ID                          724
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: 9346.91 23749 1121629
customer order: creation 173.78 338 20853
customer order: refuse 14.26 358 1711
order to supplier: creation 46.93 1298 5632
order to supplier: removal 3.51 114517 421
invoice (draft): creation 46.27 3521 5552
invoice (draft): removal 53.08 978 6370
invoice accept: apply 41.96 18283 5035
invoice accept: cancel 48.29 774 5795
customer reserve: creation 3866.44 181 463973
customer reserve: removal 4753.14 64 570377
realization accept: apply 83.15 170 9978
realization accept: cancel 85.33 126 10240
payment from customer: creation 60.11 100 7213
payment from customer: removal 6.04 70 725
payment to supplier: creation 49.02 134 5882
payment to supplier: removal 3.27 77 392
service: total inventory turnovers 4.65 1317 558
service: total monetary turnovers 6.88 31 826
service: aggregate perf. data 0.77 21868 92
service: refresh index statistics 0.03 310775 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-07-2022 19:40:36 to 20-07-2022 19:40:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1329
TEST_TIME 2 2777
TEST_TIME 3 3606
TEST_TIME 4 4618
TEST_TIME 5 5451
TEST_TIME 6 5547
TEST_TIME 7 5761
TEST_TIME 8 6271
TEST_TIME 9 6165
TEST_TIME 10 6460
TEST_TIME 11 6542
TEST_TIME 12 6475
TEST_TIME 13 6528
TEST_TIME 14 6573
TEST_TIME 15 6611
TEST_TIME 16 6934
TEST_TIME 17 6875
TEST_TIME 18 6988
TEST_TIME 19 7148
TEST_TIME 20 7606
TEST_TIME 21 7733
TEST_TIME 22 7933
TEST_TIME 23 8108
TEST_TIME 24 8328
TEST_TIME 25 8642
TEST_TIME 26 9241
TEST_TIME 27 10677
TEST_TIME 28 11201
TEST_TIME 29 11398
TEST_TIME 30 11245
TEST_TIME 31 10986
TEST_TIME 32 10728
TEST_TIME 33 10728
TEST_TIME 34 10677
TEST_TIME 35 10610
TEST_TIME 36 10503
TEST_TIME 37 10337
TEST_TIME 38 10281
TEST_TIME 39 10144
TEST_TIME 40 10105
TEST_TIME 41 10051
TEST_TIME 42 9968
TEST_TIME 43 9933
TEST_TIME 44 9867
TEST_TIME 45 9767
TEST_TIME 46 9740
TEST_TIME 47 9557
TEST_TIME 48 9705
TEST_TIME 49 9609
TEST_TIME 50 9517
TEST_TIME 51 9520
TEST_TIME 52 9605
TEST_TIME 53 9557
TEST_TIME 54 9673
TEST_TIME 55 9682
TEST_TIME 56 9638
TEST_TIME 57 9797
TEST_TIME 58 9737
TEST_TIME 59 9700
TEST_TIME 60 9857
TEST_TIME 61 9851
TEST_TIME 62 9848
TEST_TIME 63 9909
TEST_TIME 64 9971
TEST_TIME 65 9940
TEST_TIME 66 9804
TEST_TIME 67 9719
TEST_TIME 68 9655
TEST_TIME 69 9675
TEST_TIME 70 9600
TEST_TIME 71 9545
TEST_TIME 72 9503
TEST_TIME 73 9445
TEST_TIME 74 9398
TEST_TIME 75 9434
TEST_TIME 76 9426
TEST_TIME 77 9345
TEST_TIME 78 9364
TEST_TIME 79 9321
TEST_TIME 80 9277
TEST_TIME 81 9265
TEST_TIME 82 9274
TEST_TIME 83 9292
TEST_TIME 84 9252
TEST_TIME 85 9226
TEST_TIME 86 9276
TEST_TIME 87 9265
TEST_TIME 88 9279
TEST_TIME 89 9309
TEST_TIME 90 9324
TEST_TIME 91 9406
TEST_TIME 92 9372
TEST_TIME 93 9377
TEST_TIME 94 9453
TEST_TIME 95 9511
TEST_TIME 96 9588
TEST_TIME 97 9498
TEST_TIME 98 9430
TEST_TIME 99 9385
TEST_TIME 100 9421
TEST_TIME 101 9348
TEST_TIME 102 9325
TEST_TIME 103 9279
TEST_TIME 104 9254
TEST_TIME 105 9264
TEST_TIME 106 9252
TEST_TIME 107 9318
TEST_TIME 108 9275
TEST_TIME 109 9333
TEST_TIME 110 9330
TEST_TIME 111 9389
TEST_TIME 112 9367
TEST_TIME 113 9373
TEST_TIME 114 9414
TEST_TIME 115 9393
TEST_TIME 116 9415
TEST_TIME 117 9484
TEST_TIME 118 9484
TEST_TIME 119 9421
TEST_TIME 120 9348

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-07-2022 19:40:40 to 20-07-2022 19:40:43.

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 1551231 1551231 0 0.00 0 20974 6 0 0
doc_list_biud 1551231 1551231 0 0.00 0 55700 30 0 0
sp_add_invoice_to_stock 5150 5035 115 2.23 28 181484 18283 0 115
sp_cancel_adding_invoice 5795 5795 0 0.00 6 61769 775 0 0
sp_cancel_client_order 1711 1711 0 0.00 4 8331 359 0 0
sp_cancel_customer_reserve 570377 570377 0 0.00 0 23440 65 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 2 3158 71 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 7435 78 0 0
sp_cancel_supplier_invoice 6378 6370 8 0.12 2 55770 978 0 8
sp_cancel_supplier_order 421 421 0 0.00 6 1221399 114517 0 0
sp_cancel_write_off 10240 10240 0 0.00 1 21008 127 0 0
sp_client_order 20853 20853 0 0.00 3 29737 339 0 0
sp_customer_reserve 463973 463973 0 0.00 0 96721 182 0 0
sp_fill_shopping_cart 32032 32032 0 0.00 1 39354 43 0 0
sp_fill_shopping_cart_clo_res 463969 463969 0 0.00 0 27540 14 0 0
sp_get_clo_for_invoice 5030 5030 0 0.00 0 24710 318 0 0
sp_kill_cost_storno 17152 17152 0 0.00 0 10875 61 0 0
sp_kill_qstorno_ret_qs2qd 1157785 1157785 0 0.00 0 55700 35 0 0
sp_kill_qty_storno 609922 609922 0 0.00 0 55700 76 0 0
sp_lock_selected_doc 20771 20771 0 0.00 0 7897 4 0 0
sp_make_cost_storno 28103 28103 0 0.00 0 14870 49 0 0
sp_multiply_rows_for_pdistr 28103 28103 0 0.00 0 10492 26 0 0
sp_multiply_rows_for_qdistr 20853 20853 0 0.00 0 13676 150 0 0
sp_pay_from_customer 7213 7213 0 0.00 4 14203 100 0 0
sp_pay_to_supplier 5882 5882 0 0.00 4 21244 134 0 0
sp_payment_common 13095 13095 0 0.00 2 11618 26 0 0
sp_qd_handle_on_cancel_clo 1711 1711 0 0.00 0 8324 328 0 0
sp_qd_handle_on_invoice_upd_sts 10825 10825 0 0.00 0 42506 356 0 0
sp_qd_handle_on_reserve_upd_sts 9978 9978 0 0.00 0 14634 68 0 0
sp_reserve_write_off 9978 9978 0 0.00 6 16458 170 0 0
sp_supplier_invoice 5552 5552 0 0.00 5 97615 3522 0 0
sp_supplier_order 5632 5632 0 0.00 5 44880 1299 0 0
srv_aggregate_perf_data 92 92 0 0.00 2968 182586 21869 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1340 173256 42952 0 0
srv_find_qd_qs_mism 1073474 1073474 0 0.00 0 20973 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 44 68824 1318 0 0
srv_make_money_saldo 826 826 0 0.00 0 2293 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 142095 424626 310776 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 5035 5035 0 0.00 0 14637 162 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 3939 77 0 0
t$perf-norm:sp_cancel_client_order 1711 1711 0 0.00 0 3795 15 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 2335 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 3682 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 1708 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 6640 39 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 20594 1633 0 0
t$perf-norm:sp_cancel_write_off 1023 1023 0 0.00 0 9016 23 0 0
t$perf-norm:sp_client_order 20853 20853 0 0.00 0 12575 19 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 2436 10 0 0
t$perf-norm:sp_pay_from_customer 7213 7213 0 0.00 0 13075 12 0 0
t$perf-norm:sp_pay_to_supplier 5882 5882 0 0.00 0 13540 19 0 0
t$perf-norm:sp_reserve_write_off 9979 9979 0 0.00 0 11083 10 0 0
t$perf-norm:sp_supplier_invoice 5552 5552 0 0.00 0 7380 18 0 0
t$perf-norm:sp_supplier_order 5632 5632 0 0.00 0 7271 18 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 341 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 7119 121 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 702 19 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 2594 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10288 10058 230 2.23 0 7289 20 0 230
v_all_customers 38030 38030 0 0.00 0 20 1 0 0
v_all_suppliers 30614 30614 0 0.00 0 26 1 0 0
v_all_wares 41706 41706 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2063 11 0 0
v_cancel_client_order 3422 3422 0 0.00 0 5488 6 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 1625 5 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 157 3 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 6075 13 0 0
v_cancel_supplier_invoice 844 828 16 1.89 0 5852 27 0 16
v_cancel_supplier_order 842 842 0 0.00 0 3488 15 0 0
v_cancel_write_off 2046 2046 0 0.00 0 4136 17 0 0
v_max_id_clo_ord 5632 5632 0 0.00 0 963 2 0 0
v_max_id_clo_res 962 962 0 0.00 0 7345 31 0 0
v_max_id_ord_sup 5546 5546 0 0.00 0 6262 7 0 0
v_max_non_paid_invoice 1753 1753 0 0.00 0 6646 6 0 0
v_max_non_paid_realizn 3827 3827 0 0.00 0 4049 6 0 0
v_min_id_clo_ord 5632 5632 0 0.00 0 3647 8 0 0
v_min_id_clo_res 962 962 0 0.00 0 616 3 0 0
v_min_id_ord_sup 5546 5546 0 0.00 0 4732 4 0 0
v_min_non_paid_invoice 5882 5882 0 0.00 0 11016 22 0 0
v_min_non_paid_realizn 7213 7213 0 0.00 0 8050 14 0 0
v_our_firm 10448 10448 0 0.00 0 16 1 0 0
v_reserve_write_off 19956 19956 0 0.00 0 6205 7 0 0
x_lock_depdocs_on_canc_invoice 11751 11751 0 0.00 0 28043 145 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 11978 389 0 0
x_make_qstorno_1000_1200 5632 5632 0 0.00 1 44869 1219 0 0
x_make_qstorno_1200_2000 5552 5552 0 0.00 2 97606 3358 0 0
x_make_qstorno_2100_3300 463973 463973 0 0.00 0 96718 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-07-2022 19:40:44 to 20-07-2022 19:40:48.

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
17:08:29 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:30 23014 8192000000 9900758528 10211266560 982328896 10.707 100 83 166 0 1010063728 5860592 21874240
17:10:56 20179 8192000000 9954237728 10275545088 1031062976 11.179 100 93 186 0 1063736832 8748288 23925568
17:12:21 18119 8192000000 9980220272 10314104832 1061976016 11.475 100 83 164 1 1096209056 13864112 20368928
17:13:46 8962 8192000000 10026227936 10370224128 1111647584 11.948 100 83 166 0 1142974960 11148544 20178832
17:14:58 6020 8192000000 10080498912 10439426048 1168335872 12.481 100 79 158 0 1198879120 11908416 18634832
17:16:06 2421 8192000000 10129472944 10497671168 1220663536 12.968 100 91 180 1 1255403088 14114352 20625200
17:17:10 1096 8192000000 10167065168 10542333952 1254921824 13.283 100 88 173 3 1287244656 13337632 18985200
17:18:13 1192 8192000000 10192885008 10575679488 1278180944 13.496 100 83 164 1 1309383552 12751952 18450656
17:19:15 24302 8192000000 10202643120 10598506496 1296832080 13.666 100 55 110 0 1319598560 9497088 13269392
17:20:40 8878 8192000000 10190362352 10606661632 1306920016 13.758 100 25 50 0 1319220912 6617152 5683744
17:21:51 10720 8192000000 10204758256 10617417728 1317959024 13.858 100 21 42 0 1327970464 5283152 4728288
17:23:05 14466 8192000000 10207373168 10627047424 1323571712 13.909 100 12 24 0 1332199904 5829104 2799088
17:24:21 22819 8192000000 10215851728 10639720448 1326599952 13.936 100 36 72 0 1344842160 10270448 7971760
17:25:45 5222 8192000000 10229847328 10647281664 1333248320 13.996 100 35 70 1 1348949664 7958672 7742672
17:26:53 8017 8192000000 10219523824 10642178048 1330184816 13.969 100 29 57 0 1342541424 5632048 6724560
17:28:04 12276 8192000000 10200652800 10618843136 1294055168 13.641 98 22 42 0 1303939632 4652528 5231936
17:29:20 28636 8192000000 10174379360 10587451392 1235988944 13.109 95 24 48 0 1246179120 4671280 5518896
17:30:50 5954 8192000000 10132235392 10527309824 1225609280 13.014 98 42 84 1 1241545312 5886016 10050016
17:31:56 5725 8192000000 10147384208 10548666368 1243950144 13.183 99 16 30 0 1253063776 5910032 3203600
17:33:02 11762 8192000000 10156933968 10561085440 1245911664 13.201 99 22 44 2 1256798208 6114736 4771808
17:34:16 30038 8192000000 10158500768 10570194944 1269996880 13.422 100 21 42 1 1280183584 5554432 4632272
17:35:47 7175 8192000000 10185087136 10595389440 1291595120 13.619 100 28 57 0 1304190352 6197136 6398096
17:36:57 8208 8192000000 10194104912 10609811456 1306544560 13.755 100 20 40 0 1316730672 5621936 4564176
17:38:10 66344 8192000000 10219433936 10629640192 1316929504 13.849 100 31 59 2 1327828352 4770976 6127872
17:40:31 22924 8192000000 10255997056 10668531712 1350410384 14.151 100 52 104 1 1373748160 11414160 11923616
17:41:58 12240 8192000000 10266011872 10681954304 1365025760 14.282 100 48 96 2 1387568896 10857392 11685744
17:43:30 5752 8192000000 10283221984 10704334848 1374419360 14.367 100 44 85 0 1396159936 10987552 10753024
17:44:40 5989 8192000000 10305667712 10723868672 1384925728 14.461 100 66 132 0 1415423408 14467184 16030496
17:45:48 4849 8192000000 10313440000 10737115136 1397098128 14.569 100 62 117 1 1428267072 17850976 13317968
17:46:57 6045 8192000000 10332829120 10753343488 1405710880 14.646 100 62 122 1 1439801680 19940928 14149872
17:48:07 6708 8192000000 10325741984 10751488000 1397548400 14.573 99 52 104 0 1431658512 21708544 12401568
17:49:18 5304 8192000000 10338626576 10757062656 1404332496 14.634 100 63 125 0 1444419424 24798272 15288656
17:50:28 2790 8192000000 10313117952 10729197568 1379988128 14.416 99 59 116 0 1423663984 29616496 14059360
17:51:35 4290 8192000000 10350141136 10765529088 1389212240 14.499 99 58 116 0 1443784224 40192416 14379568
17:52:47 3620 8192000000 10353106480 10755936256 1380232224 14.419 98 78 152 0 1445440080 46519072 18688784
17:53:56 5773 8192000000 10379368896 10788425728 1410386816 14.687 100 75 145 1 1482376640 53406032 18583792
17:55:04 1636 8192000000 10383572176 10795134976 1412119104 14.703 100 74 144 0 1486016560 55019072 18878384
17:56:08 3098 8192000000 10404611216 10811863040 1423194336 14.801 100 80 158 0 1504666288 60839312 20632640
17:57:19 1452 8192000000 10393461584 10800062464 1410949392 14.692 100 82 162 2 1495140640 63180768 21010480
17:58:25 1340 8192000000 10413305552 10818342912 1419805808 14.771 100 96 191 1 1514174208 70103136 24265264
17:59:33 1676 8192000000 10414525920 10821689344 1420694448 14.779 100 83 163 0 1509301328 67177824 21429056
18:00:41 13257 8192000000 10382107472 10802655232 1400699632 14.601 100 45 91 0 1485507904 72339088 12469184
18:02:10 15196 8192000000 10358807024 10786574336 1378293600 14.401 100 41 82 1 1469899136 79586320 12019216
18:03:39 9169 8192000000 10352734448 10778923008 1366274208 14.294 100 50 100 1 1458282640 77784960 14223472
18:04:51 28301 8192000000 10324746400 10767196160 1350284704 14.150 100 23 46 0 1438420096 80223632 7911760
18:06:22 42077 8192000000 10286779760 10720296960 1352699984 14.172 99 18 35 0 1382170608 24923680 4546944
18:08:05 11791 8192000000 10270679296 10696511488 1339037568 14.049 99 64 121 1 1360289600 7998368 13253664
18:09:23 47436 8192000000 10274651088 10709876736 1344899648 14.102 98 22 44 0 1359689744 10326960 4463136
18:11:29 100022 8192000000 10269194816 10719883264 1343175760 14.086 98 28 56 0 1363284448 13520080 6588608
18:14:10 50986 8192000000 10255695136 10697576448 1342366176 14.079 98 28 53 1 1355115856 6932976 5816704
18:16:04 76023 8192000000 10271057696 10717499392 1358334752 14.222 99 19 38 0 1373757296 11023616 4398928
18:18:37 19535 8192000000 10289508656 10740297728 1383085744 14.444 100 25 50 0 1400987312 12328384 5573184
18:20:14 35934 8192000000 10297897904 10737684480 1372785568 14.352 99 49 98 1 1398905568 13277856 12842144
18:21:58 173256 8192000000 10293533008 10761981952 1382853968 14.442 99 7 14 0 1397305376 12847888 1603520
18:26:07 90723 8192000000 10296033392 10779770880 1391661792 14.521 100 7 14 0 1409326320 16169536 1494992
18:28:38 68286 8192000000 10312301840 10771210240 1384900480 14.460 99 20 39 0 1405374816 16047152 4427184
18:30:49 54874 8192000000 10320098000 10765983744 1362285792 14.258 97 29 58 0 1377130976 8439744 6405440
18:32:47 65891 8192000000 10304301264 10764664832 1382929952 14.443 99 18 36 0 1400081712 13311728 3840032
18:34:55 33519 8192000000 10296372192 10756018176 1386193184 14.472 99 17 34 0 1399354112 9341232 3819696
18:36:29 70547 8192000000 10295327216 10746306560 1359996640 14.237 97 22 42 0 1376707952 11668320 5042992
18:38:40 52509 8192000000 10282451872 10727923712 1319548560 13.873 95 12 24 0 1333755536 11918384 2288592
18:40:34 41929 8192000000 10280470736 10723377152 1310725472 13.793 95 15 30 0 1324434512 10665088 3043952
18:42:24 70703 8192000000 10266568240 10713120768 1320208672 13.879 97 5 10 0 1333789488 12774544 806272
18:44:35 76059 8192000000 10268138752 10713071616 1341098848 14.067 98 22 44 0 1355620016 9312176 5208992
18:46:52 44692 8192000000 10253852928 10707697664 1335649360 14.018 98 12 24 0 1349848224 11197264 3001600
18:48:37 33547 8192000000 10266077072 10724253696 1368562912 14.314 100 18 36 0 1379655184 6948448 4143824
18:50:14 89553 8192000000 10279971136 10729906176 1349304384 14.141 97 18 36 0 1363785696 10624128 3857184
18:53:17 54033 8192000000 10283386656 10738749440 1369223280 14.320 99 22 44 0 1385620848 11704016 4693552
18:55:32 26344 8192000000 10285620528 10729254912 1370969248 14.336 100 49 98 2 1394498176 12139856 11389072
18:57:13 66499 8192000000 10249360160 10709286912 1326650912 13.937 97 15 30 0 1342426272 12193632 3581728
18:59:21 70636 8192000000 10267117568 10735640576 1369345840 14.321 100 11 22 0 1382151776 10186800 2619136
19:01:32 36805 8192000000 10263587856 10722877440 1350589136 14.153 99 24 48 0 1365256000 9722848 4944016
19:03:09 27710 8192000000 10283582000 10726952960 1356901328 14.210 99 37 74 0 1378493312 13665504 7926480
19:04:40 47498 8192000000 10292276144 10736975872 1353645072 14.180 98 36 72 0 1371597904 10433712 7519120
19:06:29 66103 8192000000 10285639840 10745757696 1330639360 13.973 96 26 50 0 1353525632 17174560 5711712
19:08:40 21859 8192000000 10295505840 10738089984 1378343744 14.402 100 30 60 0 1395200928 10405072 6452112
19:10:06 41563 8192000000 10304722720 10743328768 1372539120 14.350 99 41 77 0 1389414096 7946672 8928304
19:11:48 44750 8192000000 10306456416 10759098368 1373843584 14.361 99 32 64 0 1392358864 11628992 6886288
19:13:34 95125 8192000000 10304592816 10783424512 1380593824 14.422 99 9 18 0 1412372032 29774768 2003440
19:16:40 56990 8192000000 10302910064 10743468032 1362422000 14.259 98 46 92 0 1389738560 17720240 9596320
19:18:37 49746 8192000000 10281132576 10756714496 1387094784 14.480 100 4 8 1 1397325504 9584960 645760
19:20:27 51337 8192000000 10291602880 10752671744 1380426448 14.420 99 16 31 0 1394875456 10308688 4140320
19:22:19 79840 8192000000 10294112176 10773614592 1382462000 14.439 99 13 26 0 1400647600 15009008 3176592
19:24:43 84927 8192000000 10276390912 10743119872 1336429232 14.025 96 18 37 0 1354974592 14251840 4293520
19:27:18 80585 8192000000 10279359136 10737258496 1348373936 14.133 98 12 24 0 1361958976 11101312 2483728
19:29:39 84653 8192000000 10291842848 10753101824 1365156704 14.284 99 14 28 0 1388742320 20372512 3213104
19:32:04 57982 8192000000 10260653632 10717577216 1331075296 13.977 97 20 38 0 1348609680 13171744 4362640
19:34:13 29074 8192000000 10274576592 10725163008 1325274928 13.924 97 28 56 0 1345168864 13769488 6124448
19:35:49 76941 8192000000 10275289920 10726137856 1330565408 13.972 97 9 19 0 1344669824 12129968 1974448
19:38:06 49026 8192000000 8984992752 9180483584 159804192 1.913 12 2 4 0 163814800 3673952 336656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-07-2022 19:40:50 to 20-07-2022 19:41:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 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 20-07-2022 19:41:06 to 20-07-2022 19:41:08.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          242647
MON$OLDEST_ACTIVE               242648
MON$OLDEST_SNAPSHOT             242648
MON$NEXT_TRANSACTION            242648
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-03 16:41:55.3470
MON$PAGES                       2038048
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33609 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33609 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33609 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:247: 
:248: mscs-test-161.budzdorov.ru    Wed Jul 20 19:39:21 2022
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:250: 
:251: 
:252: mscs-test-161.budzdorov.ru    Wed Jul 20 19:39:22 2022
:253:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:254: 
--- end of diff output ---

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

20.07.22 19:41:09. There are 3 logs in /var/tmp/logs-oltp30 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-20 19:41:11 No severe PSQL-related problems occured
20.07.22 19:41:13. End of report.