Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.07.2022 01:39
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 16096952 kB
MemAvailable 31046576 kB
Buffers 0 kB
Cached 15605340 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-22 01:38:22
DTS_END                         2022-07-22 01:38:22.3040
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/56710
TRN_ID                          241379
ATT_ID                          720
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: 9441.73 20082 1133007
customer order: creation 175.63 351 21076
customer order: refuse 14.41 348 1729
order to supplier: creation 45.20 1208 5424
order to supplier: removal 3.38 110699 406
invoice (draft): creation 46.18 3293 5542
invoice (draft): removal 53.81 842 6457
invoice accept: apply 42.63 16502 5115
invoice accept: cancel 48.09 749 5771
customer reserve: creation 3965.65 162 475878
customer reserve: removal 4752.02 61 570243
realization accept: apply 83.63 168 10036
realization accept: cancel 80.91 113 9709
payment from customer: creation 58.86 133 7063
payment from customer: removal 5.73 68 687
payment to supplier: creation 50.22 152 6026
payment to supplier: removal 3.23 93 388
service: total inventory turnovers 4.88 936 585
service: total monetary turnovers 6.43 56 771
service: aggregate perf. data 0.81 18681 97
service: refresh index statistics 0.03 247031 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-07-2022 01:39:27 to 22-07-2022 01:39:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5963
TEST_TIME 2 4597
TEST_TIME 3 4473
TEST_TIME 4 4353
TEST_TIME 5 4913
TEST_TIME 6 4729
TEST_TIME 7 4572
TEST_TIME 8 4708
TEST_TIME 9 4646
TEST_TIME 10 4774
TEST_TIME 11 4880
TEST_TIME 12 5095
TEST_TIME 13 5390
TEST_TIME 14 5379
TEST_TIME 15 5902
TEST_TIME 16 6258
TEST_TIME 17 6508
TEST_TIME 18 6816
TEST_TIME 19 7264
TEST_TIME 20 7386
TEST_TIME 21 7481
TEST_TIME 22 7740
TEST_TIME 23 7584
TEST_TIME 24 8307
TEST_TIME 25 8700
TEST_TIME 26 9557
TEST_TIME 27 10540
TEST_TIME 28 10790
TEST_TIME 29 10633
TEST_TIME 30 10476
TEST_TIME 31 10213
TEST_TIME 32 10199
TEST_TIME 33 10096
TEST_TIME 34 10145
TEST_TIME 35 10079
TEST_TIME 36 9907
TEST_TIME 37 9867
TEST_TIME 38 9751
TEST_TIME 39 9895
TEST_TIME 40 10081
TEST_TIME 41 9986
TEST_TIME 42 9865
TEST_TIME 43 9853
TEST_TIME 44 9666
TEST_TIME 45 9645
TEST_TIME 46 9531
TEST_TIME 47 9420
TEST_TIME 48 9536
TEST_TIME 49 9554
TEST_TIME 50 9540
TEST_TIME 51 9473
TEST_TIME 52 9458
TEST_TIME 53 9575
TEST_TIME 54 9670
TEST_TIME 55 9628
TEST_TIME 56 9642
TEST_TIME 57 9727
TEST_TIME 58 9701
TEST_TIME 59 9855
TEST_TIME 60 9817
TEST_TIME 61 9964
TEST_TIME 62 9910
TEST_TIME 63 10002
TEST_TIME 64 9855
TEST_TIME 65 9855
TEST_TIME 66 9771
TEST_TIME 67 9763
TEST_TIME 68 9728
TEST_TIME 69 9670
TEST_TIME 70 9644
TEST_TIME 71 9585
TEST_TIME 72 9545
TEST_TIME 73 9519
TEST_TIME 74 9477
TEST_TIME 75 9443
TEST_TIME 76 9411
TEST_TIME 77 9360
TEST_TIME 78 9466
TEST_TIME 79 9458
TEST_TIME 80 9473
TEST_TIME 81 9524
TEST_TIME 82 9501
TEST_TIME 83 9537
TEST_TIME 84 9650
TEST_TIME 85 9687
TEST_TIME 86 9636
TEST_TIME 87 9676
TEST_TIME 88 9681
TEST_TIME 89 9731
TEST_TIME 90 9635
TEST_TIME 91 9621
TEST_TIME 92 9555
TEST_TIME 93 9578
TEST_TIME 94 9575
TEST_TIME 95 9563
TEST_TIME 96 9563
TEST_TIME 97 9586
TEST_TIME 98 9552
TEST_TIME 99 9581
TEST_TIME 100 9612
TEST_TIME 101 9607
TEST_TIME 102 9555
TEST_TIME 103 9592
TEST_TIME 104 9579
TEST_TIME 105 9557
TEST_TIME 106 9661
TEST_TIME 107 9690
TEST_TIME 108 9670
TEST_TIME 109 9595
TEST_TIME 110 9530
TEST_TIME 111 9529
TEST_TIME 112 9522
TEST_TIME 113 9507
TEST_TIME 114 9484
TEST_TIME 115 9500
TEST_TIME 116 9437
TEST_TIME 117 9505
TEST_TIME 118 9494
TEST_TIME 119 9499
TEST_TIME 120 9443

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-07-2022 01:39:31 to 22-07-2022 01:39:34.

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 1571721 1571721 0 0.00 0 21917 6 0 0
doc_list_biud 1571718 1571718 0 0.00 0 57346 28 0 0
sp_add_invoice_to_stock 5239 5115 124 2.36 53 146543 16502 0 124
sp_cancel_adding_invoice 5771 5771 0 0.00 4 44312 749 0 0
sp_cancel_client_order 1729 1729 0 0.00 4 11361 349 0 0
sp_cancel_customer_reserve 570243 570243 0 0.00 0 57347 62 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 2 5479 69 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 2 6292 93 0 0
sp_cancel_supplier_invoice 6470 6457 13 0.20 1 17765 842 0 13
sp_cancel_supplier_order 406 406 0 0.00 11 1158909 110700 0 0
sp_cancel_write_off 9709 9709 0 0.00 1 11608 114 0 0
sp_client_order 21076 21076 0 0.00 3 51158 352 0 0
sp_customer_reserve 475878 475878 0 0.00 0 38113 163 0 0
sp_fill_shopping_cart 32032 32032 0 0.00 1 10590 37 0 0
sp_fill_shopping_cart_clo_res 475876 475876 0 0.00 0 17273 13 0 0
sp_get_clo_for_invoice 5113 5113 0 0.00 0 30583 318 0 0
sp_kill_cost_storno 16555 16555 0 0.00 0 8349 58 0 0
sp_kill_qstorno_ret_qs2qd 1157058 1157058 0 0.00 0 57342 34 0 0
sp_kill_qty_storno 609464 609464 0 0.00 0 57345 72 0 0
sp_lock_selected_doc 20892 20892 0 0.00 0 6281 4 0 0
sp_make_cost_storno 28238 28238 0 0.00 0 21914 54 0 0
sp_multiply_rows_for_pdistr 28238 28238 0 0.00 0 6954 27 0 0
sp_multiply_rows_for_qdistr 21076 21076 0 0.00 0 51150 175 0 0
sp_pay_from_customer 7063 7063 0 0.00 4 12456 133 0 0
sp_pay_to_supplier 6026 6026 0 0.00 4 21924 153 0 0
sp_payment_common 13088 13088 0 0.00 1 10227 34 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 11354 324 0 0
sp_qd_handle_on_invoice_upd_sts 10884 10884 0 0.00 0 38086 317 0 0
sp_qd_handle_on_reserve_upd_sts 10036 10036 0 0.00 0 26997 60 0 0
sp_reserve_write_off 10036 10036 0 0.00 6 27008 169 0 0
sp_supplier_invoice 5542 5542 0 0.00 5 84730 3293 0 0
sp_supplier_order 5424 5424 0 0.00 5 40511 1208 0 0
srv_aggregate_perf_data 97 97 0 0.00 2436 160678 18682 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 882 103801 40930 0 0
srv_find_qd_qs_mism 1083515 1083515 0 0.00 0 16753 5 0 0
srv_make_invnt_saldo 585 585 0 0.00 45 26039 937 0 0
srv_make_money_saldo 771 771 0 0.00 1 15901 57 0 0
srv_recalc_idx_stat 4 4 0 0.00 116915 379074 247031 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5115 5115 0 0.00 0 21342 189 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 3909 99 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 7059 20 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 7246 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 501 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 4583 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 2440 15 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 22681 1699 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 2907 9 0 0
t$perf-norm:sp_client_order 21076 21076 0 0.00 0 8986 18 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 10593 32 0 0
t$perf-norm:sp_pay_from_customer 7063 7063 0 0.00 0 3930 12 0 0
t$perf-norm:sp_pay_to_supplier 6026 6026 0 0.00 0 6866 10 0 0
t$perf-norm:sp_reserve_write_off 10036 10036 0 0.00 0 8321 17 0 0
t$perf-norm:sp_supplier_invoice 5542 5542 0 0.00 0 4846 15 0 0
t$perf-norm:sp_supplier_order 5424 5424 0 0.00 0 6767 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 859 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 209 6 0 0
t$perf-norm:srv_make_invnt_saldo 585 585 0 0.00 0 2061 17 0 0
t$perf-norm:srv_make_money_saldo 771 771 0 0.00 0 1743 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 3 0 0
v_add_invoice_to_stock 10474 10226 248 2.36 0 4906 18 0 248
v_all_customers 38144 38144 0 0.00 0 84 1 0 0
v_all_suppliers 31002 31002 0 0.00 0 58 1 0 0
v_all_wares 42150 42150 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 883 8 0 0
v_cancel_client_order 3458 3458 0 0.00 0 3639 5 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 2473 6 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 1391 4 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 2795 6 0 0
v_cancel_supplier_invoice 910 884 26 2.85 0 4702 27 0 26
v_cancel_supplier_order 812 812 0 0.00 0 3957 10 0 0
v_cancel_write_off 2068 2068 0 0.00 0 2823 15 0 0
v_max_id_clo_ord 5423 5423 0 0.00 0 7941 3 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 3003 12 0 0
v_max_id_ord_sup 5533 5533 0 0.00 0 4400 5 0 0
v_max_non_paid_invoice 1481 1481 0 0.00 0 337 3 0 0
v_max_non_paid_realizn 3859 3859 0 0.00 0 3316 4 0 0
v_min_id_clo_ord 5423 5423 0 0.00 0 7928 18 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 2062 6 0 0
v_min_id_ord_sup 5533 5533 0 0.00 0 9018 10 0 0
v_min_non_paid_invoice 6026 6026 0 0.00 0 10223 27 0 0
v_min_non_paid_realizn 7062 7062 0 0.00 0 8283 26 0 0
v_our_firm 10412 10412 0 0.00 0 19 1 0 0
v_reserve_write_off 20072 20072 0 0.00 0 5576 6 0 0
x_lock_depdocs_on_canc_invoice 11786 11786 0 0.00 0 17362 116 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 3942 336 0 0
x_make_qstorno_1000_1200 5424 5424 0 0.00 1 40474 1127 0 0
x_make_qstorno_1200_2000 5542 5542 0 0.00 3 82853 3163 0 0
x_make_qstorno_2100_3300 475878 475878 0 0.00 0 36698 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-07-2022 01:39:35 to 22-07-2022 01:39:39.

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
23:08:26 12 8192000000 8801964544 8814235648 7819504 0.095 2 2 3 0 7962032 67584 74944
23:09:27 20412 8192000000 9927265152 10242519040 1007374784 10.950 100 89 176 0 1036571280 6705040 22491456
23:10:56 14086 8192000000 9968897600 10299777024 1050921520 11.370 100 81 163 0 1080233776 9053504 20258752
23:12:20 19432 8192000000 10014496208 10353274880 1091265968 11.755 100 89 178 0 1124804448 11405520 22132960
23:13:45 8311 8192000000 10038283008 10387214336 1121004880 12.036 100 82 164 0 1152776224 11944256 19827088
23:14:56 5567 8192000000 10072542832 10429255680 1157909856 12.384 100 84 167 0 1190240400 12959824 19370720
23:16:04 5924 8192000000 10115174080 10484477952 1204537680 12.818 100 82 165 0 1235541312 12578048 18425584
23:17:11 3313 8192000000 10152618016 10528112640 1242132112 13.166 100 82 164 2 1273384144 12590080 18661952
23:18:16 3670 8192000000 10185710000 10567684096 1268870880 13.411 100 91 178 1 1303415264 14469264 20075120
23:19:23 1109 8192000000 10212241168 10597584896 1298942768 13.686 100 84 166 1 1330301360 13326560 18032032
23:20:25 7252 8192000000 10207954160 10604371968 1303618032 13.728 100 49 98 0 1322635648 8347056 10670560
23:21:33 3033 8192000000 10205584432 10620776448 1313596864 13.819 100 35 70 2 1333124512 11182336 8345312
23:22:39 6936 8192000000 10198698080 10617458688 1316655712 13.846 100 16 32 0 1326295712 6316512 3323488
23:23:46 11915 8192000000 10214340304 10635931648 1324984432 13.922 100 15 30 0 1333923424 5578352 3360640
23:24:58 39847 8192000000 10246198432 10654470144 1333735712 14.001 100 52 101 2 1351253408 5028576 12489120
23:26:39 4960 8192000000 10229057392 10650701824 1334955664 14.012 100 27 55 0 1348150944 7027072 6168208
23:27:44 12620 8192000000 10214351776 10637602816 1317992240 13.859 99 20 41 1 1328124800 5172656 4959904
23:29:02 19188 8192000000 10177632208 10583203840 1232900912 13.081 94 23 46 0 1242237136 4566208 4770016
23:30:21 15883 8192000000 10137838432 10550779904 1202750320 12.802 94 18 36 0 1212011696 4889040 4372336
23:31:38 6002 8192000000 10140105264 10542731264 1241262480 13.158 99 23 46 1 1252817600 5876400 5678720
23:32:46 8451 8192000000 10140133776 10539130880 1224860080 13.007 98 26 51 0 1236286288 5665328 5760880
23:33:55 8730 8192000000 10154264560 10562330624 1263996592 13.367 100 20 38 0 1273724848 5300304 4427952
23:35:04 30291 8192000000 10177249568 10584166400 1265993552 13.385 99 25 50 0 1277566272 6236016 5336704
23:36:35 7577 8192000000 10187895808 10601369600 1296348384 13.662 100 28 53 0 1308407376 6605808 5453184
23:37:43 9462 8192000000 10194269936 10611433472 1303462736 13.727 100 18 34 0 1312472544 5088848 3920960
23:39:00 8650 8192000000 10236605088 10648760320 1334399456 14.007 100 41 80 1 1349426368 6180624 8846288
23:40:12 69129 8192000000 10242689904 10664693760 1335374432 14.016 99 36 72 1 1350949504 7652496 7922576
23:42:26 7383 8192000000 10267720800 10683834368 1361650976 14.252 100 45 90 0 1382024624 9938224 10435424
23:43:40 22724 8192000000 10289600656 10708967424 1376688192 14.387 100 40 78 1 1396117648 10107840 9321616
23:45:06 11723 8192000000 10302619952 10726506496 1392110784 14.525 100 37 72 0 1418044272 17100320 8833168
23:46:25 25518 8192000000 10315969760 10740727808 1386908816 14.478 99 50 100 0 1419799984 20735888 12155280
23:48:05 4339 8192000000 10352832736 10772410368 1420528560 14.777 100 65 126 3 1464825184 29037168 15259456
23:49:46 2979 8192000000 10364192016 10785226752 1425037392 14.817 100 63 123 2 1472765408 31911072 15816944
23:50:54 14202 8192000000 10345750512 10772840448 1403837312 14.629 98 49 96 0 1456572240 39636576 13098352
23:52:13 2068 8192000000 10368110240 10787254272 1427504832 14.839 100 68 132 0 1478884416 35161120 16218464
23:53:21 3539 8192000000 10390881088 10803781632 1432198080 14.881 100 84 168 0 1499139168 44778112 22162976
23:54:31 904 8192000000 10383404224 10794389504 1405810320 14.647 99 84 167 1 1474344432 48096624 20437488
23:55:35 5544 8192000000 10331530832 10745704448 1375073216 14.372 99 53 101 1 1436541472 47680960 13787296
23:56:46 881 8192000000 10365228976 10768277504 1392346448 14.527 100 82 162 1 1466910464 54348528 20215488
23:57:55 8996 8192000000 10364108288 10777817088 1397538240 14.573 100 45 87 0 1462224240 52634320 12051680
23:59:07 7525 8192000000 10362002080 10780282880 1399431072 14.590 100 45 90 0 1463684816 51957280 12296464
00:00:40 11065 8192000000 10345977072 10774192128 1384535648 14.457 100 44 89 0 1457707696 60700576 12471472
00:02:00 12808 8192000000 10345363536 10775212032 1355530960 14.197 98 40 80 1 1437561280 70191360 11838960
00:03:27 27834 8192000000 10300223392 10736939008 1338948928 14.048 99 22 44 0 1400556720 54225184 7382608
00:05:22 49318 8192000000 10263166944 10705145856 1344223728 14.095 99 15 30 0 1371322608 23074928 4023952
00:07:13 65495 8192000000 10233520400 10668982272 1289601920 13.601 96 16 32 0 1304837072 10824976 4410176
00:09:19 33402 8192000000 10247602336 10678202368 1322558192 13.900 99 43 81 0 1339404816 6892480 9954144
00:11:03 50940 8192000000 10278553376 10709118976 1345795728 14.110 99 40 79 0 1366008624 11405264 8807632
00:13:02 73100 8192000000 10284899616 10735796224 1378122320 14.400 100 11 22 0 1391724624 11091360 2510944
00:15:17 39865 8192000000 10313754736 10761322496 1399002672 14.586 100 34 64 0 1425625104 17941968 8680464
00:17:17 51064 8192000000 10265569088 10716336128 1357827424 14.218 98 24 48 1 1383914432 20073472 6013536
00:19:09 34402 8192000000 10281272912 10730885120 1372407552 14.349 100 14 28 1 1384193536 8529328 3256656
00:20:49 48653 8192000000 10269983168 10722689024 1361167824 14.248 98 21 42 1 1374545008 8559792 4817392
00:22:38 35850 8192000000 10293147488 10742943744 1384868096 14.460 100 26 47 1 1396307984 5447600 5992288
00:24:30 69584 8192000000 10310229680 10758504448 1398767312 14.584 100 20 40 1 1418617056 15568160 4281584
00:26:46 38722 8192000000 10293024144 10763833344 1394884992 14.549 100 7 12 0 1410840352 14487440 1467920
00:28:35 56879 8192000000 10306464912 10758811648 1394642032 14.547 100 18 35 0 1407951344 9531728 3777584
00:30:32 39497 8192000000 10320125136 10785030144 1411729376 14.699 100 24 48 0 1430126608 12516256 5880976
00:32:14 63663 8192000000 10324137920 10781962240 1409146048 14.676 100 15 30 0 1426321216 13849504 3325664
00:34:21 22900 8192000000 10324757184 10779947008 1404734048 14.637 99 32 64 0 1425996496 14197584 7064864
00:35:49 27056 8192000000 10325494432 10768941056 1403373984 14.625 100 40 80 0 1422987264 10585184 9028096
00:37:16 74479 8192000000 10311441952 10756616192 1373424400 14.358 98 16 31 0 1391814944 15069616 3320928
00:39:38 103801 8192000000 10263740160 10726346752 1290696288 13.611 92 8 16 0 1303976816 11652064 1628464
00:42:23 53151 8192000000 10274486608 10712674304 1327061984 13.941 96 45 89 0 1344397216 7099056 10236176
00:44:32 2108 8192000000 10257914528 10703589376 1349162752 14.140 100 33 63 1 1366204160 10105936 6935472
00:45:34 67303 8192000000 10249040496 10700623872 1299882336 13.694 95 16 32 0 1312178432 8829936 3466160
00:47:42 47601 8192000000 10238427088 10691796992 1293551024 13.637 96 26 52 0 1308717856 9050320 6116512
00:49:33 59155 8192000000 10245789504 10699071488 1349313712 14.141 100 15 30 0 1361027072 7750480 3962880
00:52:13 11992 8192000000 10247263488 10684887040 1322311792 13.898 98 36 71 2 1336906224 6387024 8207408
00:53:25 54103 8192000000 10236845456 10692788224 1331871680 13.984 99 9 18 0 1340205472 6440368 1893424
00:55:19 72176 8192000000 10232851136 10690822144 1294636912 13.646 96 14 28 0 1309939600 12285488 3017200
00:57:37 59886 8192000000 10243199088 10691026944 1313797776 13.821 98 15 30 0 1330364912 13056304 3510832
00:59:50 23155 8192000000 10269611632 10704207872 1333806192 14.002 99 46 92 0 1359936608 15652752 10477664
01:01:16 55912 8192000000 10273016448 10722291712 1349378112 14.142 100 30 60 1 1372929024 16795360 6755552
01:03:18 80770 8192000000 10282187296 10716454912 1348155024 14.131 99 17 34 0 1365938960 14022560 3761376
01:05:42 53191 8192000000 10273189040 10725990400 1329967648 13.967 97 16 32 0 1349652832 15938064 3747120
01:07:44 103428 8192000000 10265984512 10715299840 1329897040 13.966 97 11 22 0 1342937200 11139568 1900592
01:10:29 78268 8192000000 10267130800 10732556288 1360971408 14.246 99 11 22 0 1379018784 15247776 2799600
01:12:49 49770 8192000000 10287643008 10747576320 1370335984 14.330 99 12 24 0 1385200016 12413232 2450800
01:14:41 50495 8192000000 10299598224 10755018752 1382152544 14.436 99 29 58 0 1400130464 11481072 6496848
01:16:36 51762 8192000000 10319663872 10773688320 1401474608 14.608 100 21 42 0 1421341584 15102480 4764496
01:18:38 68279 8192000000 10305060528 10767155200 1391317824 14.518 99 21 42 0 1404288576 8608256 4362496
01:20:57 9086 8192000000 10315837968 10760318976 1385712528 14.468 99 69 138 0 1411980768 11074992 15193248
01:22:09 27906 8192000000 10302619776 10747187200 1367549200 14.305 98 21 42 0 1379667344 7404688 4713456
01:23:37 28871 8192000000 10317493984 10757054464 1380272480 14.419 99 53 104 2 1405467840 13263200 11932160
01:25:08 70548 8192000000 10288056400 10741858304 1322107440 13.896 95 10 22 0 1344727104 20431360 2188304
01:27:28 96925 8192000000 10272959680 10720931840 1341954688 14.075 97 35 70 1 1356835216 7185360 7695168
01:30:05 43897 8192000000 10265552624 10729279488 1349575424 14.144 98 15 32 0 1363536384 10190144 3770816
01:31:50 52981 8192000000 10282943104 10729349120 1360182624 14.239 99 5 10 0 1373031520 11598368 1250528
01:33:46 70801 8192000000 10279824592 10744221696 1370699408 14.333 99 15 30 0 1389331232 14683360 3948464
01:36:15 60975 8192000000 10245657504 10700238848 1309682768 13.783 96 10 20 0 1319308672 7588192 2037712
01:38:17 11229 8192000000 9148891440 9403797504 307234224 3.614 23 5 10 0 310979664 2749728 995712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-07-2022 01:39:41 to 22-07-2022 01:39:56.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-07-2022 01:39:57 to 22-07-2022 01:39:59.

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          241931
MON$OLDEST_ACTIVE               241932
MON$OLDEST_SNAPSHOT             241932
MON$NEXT_TRANSACTION            241932
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                       2023168
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 ---
:325: 
:326: mscs-test-161.budzdorov.ru    Fri Jul 22 01:38:30 2022
:327:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:328: 
--- end of diff output ---

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

22.07.22 01:40:00. 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-22 01:40:02 No severe PSQL-related problems occured
22.07.22 01:40:04. End of report.