Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.09.2021 16: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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16158880 kB
MemAvailable 30915520 kB
Buffers 0 kB
Cached 15550348 kB
SwapCached 340 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.8
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 2021-09-04 16:37:45
DTS_END                         2021-09-04 16:37:45.5900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36686
TRN_ID                          246271
ATT_ID                          666
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: 9256.93 18400 1110832
customer order: creation 178.95 351 21474
customer order: refuse 15.28 350 1833
order to supplier: creation 46.72 1231 5606
order to supplier: removal 3.43 99069 411
invoice (draft): creation 48.05 3577 5766
invoice (draft): removal 52.13 747 6255
invoice accept: apply 42.53 18008 5103
invoice accept: cancel 46.86 857 5623
customer reserve: creation 3915.97 179 469916
customer reserve: removal 4597.76 58 551731
realization accept: apply 86.04 188 10325
realization accept: cancel 88.65 100 10638
payment from customer: creation 61.30 114 7356
payment from customer: removal 5.95 109 714
payment to supplier: creation 50.95 125 6114
payment to supplier: removal 3.28 70 394
service: total inventory turnovers 5.19 940 623
service: total monetary turnovers 6.96 33 835
service: aggregate perf. data 0.93 20250 111
service: refresh index statistics 0.03 221639 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-09-2021 16:39:36 to 04-09-2021 16:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2566
TEST_TIME 2 3360
TEST_TIME 3 3904
TEST_TIME 4 4168
TEST_TIME 5 4335
TEST_TIME 6 4512
TEST_TIME 7 5418
TEST_TIME 8 5676
TEST_TIME 9 5701
TEST_TIME 10 5582
TEST_TIME 11 5738
TEST_TIME 12 6084
TEST_TIME 13 6110
TEST_TIME 14 6149
TEST_TIME 15 6184
TEST_TIME 16 6407
TEST_TIME 17 6817
TEST_TIME 18 6880
TEST_TIME 19 7834
TEST_TIME 20 7747
TEST_TIME 21 8169
TEST_TIME 22 8362
TEST_TIME 23 8768
TEST_TIME 24 8868
TEST_TIME 25 8903
TEST_TIME 26 9254
TEST_TIME 27 9497
TEST_TIME 28 10024
TEST_TIME 29 10003
TEST_TIME 30 9997
TEST_TIME 31 10049
TEST_TIME 32 10087
TEST_TIME 33 10090
TEST_TIME 34 10055
TEST_TIME 35 10149
TEST_TIME 36 10113
TEST_TIME 37 9983
TEST_TIME 38 9825
TEST_TIME 39 9808
TEST_TIME 40 9845
TEST_TIME 41 9722
TEST_TIME 42 9691
TEST_TIME 43 9699
TEST_TIME 44 9718
TEST_TIME 45 9638
TEST_TIME 46 9752
TEST_TIME 47 9723
TEST_TIME 48 9637
TEST_TIME 49 9574
TEST_TIME 50 9597
TEST_TIME 51 9590
TEST_TIME 52 9685
TEST_TIME 53 9687
TEST_TIME 54 9679
TEST_TIME 55 9623
TEST_TIME 56 9681
TEST_TIME 57 9791
TEST_TIME 58 9713
TEST_TIME 59 9687
TEST_TIME 60 9792
TEST_TIME 61 9740
TEST_TIME 62 9754
TEST_TIME 63 9767
TEST_TIME 64 9718
TEST_TIME 65 9647
TEST_TIME 66 9622
TEST_TIME 67 9548
TEST_TIME 68 9553
TEST_TIME 69 9489
TEST_TIME 70 9478
TEST_TIME 71 9422
TEST_TIME 72 9457
TEST_TIME 73 9365
TEST_TIME 74 9296
TEST_TIME 75 9262
TEST_TIME 76 9436
TEST_TIME 77 9406
TEST_TIME 78 9456
TEST_TIME 79 9471
TEST_TIME 80 9478
TEST_TIME 81 9486
TEST_TIME 82 9478
TEST_TIME 83 9469
TEST_TIME 84 9534
TEST_TIME 85 9515
TEST_TIME 86 9527
TEST_TIME 87 9611
TEST_TIME 88 9604
TEST_TIME 89 9551
TEST_TIME 90 9533
TEST_TIME 91 9540
TEST_TIME 92 9483
TEST_TIME 93 9496
TEST_TIME 94 9470
TEST_TIME 95 9446
TEST_TIME 96 9373
TEST_TIME 97 9371
TEST_TIME 98 9350
TEST_TIME 99 9360
TEST_TIME 100 9368
TEST_TIME 101 9356
TEST_TIME 102 9334
TEST_TIME 103 9354
TEST_TIME 104 9411
TEST_TIME 105 9393
TEST_TIME 106 9460
TEST_TIME 107 9435
TEST_TIME 108 9432
TEST_TIME 109 9422
TEST_TIME 110 9484
TEST_TIME 111 9445
TEST_TIME 112 9395
TEST_TIME 113 9384
TEST_TIME 114 9366
TEST_TIME 115 9317
TEST_TIME 116 9271
TEST_TIME 117 9275
TEST_TIME 118 9245
TEST_TIME 119 9253
TEST_TIME 120 9259

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-09-2021 16:39:39 to 04-09-2021 16:39: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 1547008 1547008 0 0.00 0 20725 6 0 0
doc_list_biud 1547007 1547007 0 0.00 0 64488 27 0 0
sp_add_invoice_to_stock 5215 5103 112 2.14 49 177579 18008 0 112
sp_cancel_adding_invoice 5623 5623 0 0.00 5 75054 858 0 0
sp_cancel_client_order 1833 1833 0 0.00 4 11535 351 0 0
sp_cancel_customer_reserve 551731 551731 0 0.00 1 64489 58 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 15372 110 0 0
sp_cancel_pay_to_supplier 394 394 0 0.00 2 3193 71 0 0
sp_cancel_supplier_invoice 6267 6255 12 0.19 2 32634 748 0 12
sp_cancel_supplier_order 411 411 0 0.00 11 939851 99069 0 0
sp_cancel_write_off 10638 10638 0 0.00 1 13945 100 0 0
sp_client_order 21474 21474 0 0.00 3 20846 352 0 0
sp_customer_reserve 469916 469916 0 0.00 0 79579 180 0 0
sp_fill_shopping_cart 32845 32845 0 0.00 1 12930 40 0 0
sp_fill_shopping_cart_clo_res 469914 469914 0 0.00 0 17562 13 0 0
sp_get_clo_for_invoice 5099 5099 0 0.00 0 36669 271 0 0
sp_kill_cost_storno 17369 17369 0 0.00 0 7791 54 0 0
sp_kill_qstorno_ret_qs2qd 1120766 1120766 0 0.00 0 64482 32 0 0
sp_kill_qty_storno 591915 591915 0 0.00 0 64488 69 0 0
sp_lock_selected_doc 21475 21475 0 0.00 0 9307 5 0 0
sp_make_cost_storno 28894 28894 0 0.00 0 20721 55 0 0
sp_multiply_rows_for_pdistr 28893 28893 0 0.00 0 7433 30 0 0
sp_multiply_rows_for_qdistr 21474 21474 0 0.00 0 12524 162 0 0
sp_pay_from_customer 7356 7356 0 0.00 4 19522 115 0 0
sp_pay_to_supplier 6114 6114 0 0.00 5 17647 126 0 0
sp_payment_common 13469 13469 0 0.00 1 17597 33 0 0
sp_qd_handle_on_cancel_clo 1833 1833 0 0.00 0 11527 317 0 0
sp_qd_handle_on_invoice_upd_sts 10722 10722 0 0.00 0 18411 341 0 0
sp_qd_handle_on_reserve_upd_sts 10325 10325 0 0.00 0 14207 60 0 0
sp_reserve_write_off 10325 10325 0 0.00 6 20747 189 0 0
sp_supplier_invoice 5766 5766 0 0.00 7 84740 3577 0 0
sp_supplier_order 5606 5606 0 0.00 5 40389 1232 0 0
srv_aggregate_perf_data 111 111 0 0.00 2101 423308 20251 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 2398 142439 43093 0 0
srv_find_qd_qs_mism 1062173 1062173 0 0.00 0 12371 5 0 0
srv_make_invnt_saldo 623 623 0 0.00 46 39381 940 0 0
srv_make_money_saldo 835 835 0 0.00 0 5226 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 108679 299870 221640 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5104 5104 0 0.00 0 14132 169 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 1814 78 0 0
t$perf-norm:sp_cancel_client_order 1833 1833 0 0.00 0 5589 18 0 0
t$perf-norm:sp_cancel_customer_reserve 800 800 0 0.00 0 15370 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 6153 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 394 394 0 0.00 0 1390 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 4193 27 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 21458 1641 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 1195 7 0 0
t$perf-norm:sp_client_order 21474 21474 0 0.00 0 19564 18 0 0
t$perf-norm:sp_customer_reserve 1034 1034 0 0.00 0 1162 9 0 0
t$perf-norm:sp_pay_from_customer 7356 7356 0 0.00 0 9470 13 0 0
t$perf-norm:sp_pay_to_supplier 6114 6114 0 0.00 0 9492 12 0 0
t$perf-norm:sp_reserve_write_off 10325 10325 0 0.00 0 7314 15 0 0
t$perf-norm:sp_supplier_invoice 5766 5766 0 0.00 0 12837 22 0 0
t$perf-norm:sp_supplier_order 5606 5606 0 0.00 0 9782 17 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 794 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 9 1 0 0
t$perf-norm:srv_make_invnt_saldo 623 623 0 0.00 0 1548 22 0 0
t$perf-norm:srv_make_money_saldo 835 835 0 0.00 0 571 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 10420 10196 224 2.14 0 4865 17 0 224
v_all_customers 38786 38786 0 0.00 0 16 1 0 0
v_all_suppliers 31804 31804 0 0.00 0 18 1 0 0
v_all_wares 42948 42948 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 393 6 0 0
v_cancel_client_order 3666 3666 0 0.00 0 1665 5 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 1913 6 0 0
v_cancel_customer_reserve 1600 1600 0 0.00 0 1692 5 0 0
v_cancel_payment_to_supplier 788 788 0 0.00 0 1177 6 0 0
v_cancel_supplier_invoice 886 862 24 2.70 0 2357 19 0 24
v_cancel_supplier_order 822 822 0 0.00 0 1246 5 0 0
v_cancel_write_off 2072 2072 0 0.00 0 1379 13 0 0
v_max_id_clo_ord 5605 5605 0 0.00 0 1607 2 0 0
v_max_id_clo_res 1034 1034 0 0.00 0 1218 10 0 0
v_max_id_ord_sup 5765 5765 0 0.00 0 5584 8 0 0
v_max_non_paid_invoice 1581 1581 0 0.00 0 2126 7 0 0
v_max_non_paid_realizn 3911 3911 0 0.00 0 6574 6 0 0
v_min_id_clo_ord 5605 5605 0 0.00 0 10945 18 0 0
v_min_id_clo_res 1034 1034 0 0.00 0 5126 13 0 0
v_min_id_ord_sup 5765 5765 0 0.00 0 7666 13 0 0
v_min_non_paid_invoice 6113 6113 0 0.00 0 7323 21 0 0
v_min_non_paid_realizn 7356 7356 0 0.00 0 8104 19 0 0
v_our_firm 11052 11052 0 0.00 0 17 1 0 0
v_reserve_write_off 20650 20650 0 0.00 0 3560 4 0 0
x_lock_depdocs_on_canc_invoice 11447 11447 0 0.00 0 11070 113 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 3757 301 0 0
x_make_qstorno_1000_1200 5606 5606 0 0.00 1 40382 1144 0 0
x_make_qstorno_1200_2000 5766 5766 0 0.00 3 83153 3436 0 0
x_make_qstorno_2100_3300 469916 469916 0 0.00 0 79577 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-09-2021 16:39:44 to 04-09-2021 16:39: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
14:07:49 18 8192000000 8805111696 8817446912 9090000 0.110 3 1 2 0 9210704 58144 62560
14:08:50 25054 8192000000 10398509584 10720280576 1443397088 14.980 100 89 178 0 1479004416 6647296 28960032
14:10:20 18794 8192000000 10442531600 10774736896 1496224304 15.443 100 90 180 0 1534110784 8924208 28962272
14:11:43 24592 8192000000 10474584704 10816917504 1527466544 15.715 100 86 172 0 1565052864 10410960 27175360
14:13:20 14203 8192000000 10560999920 10922319872 1619950144 16.509 100 81 162 1 1655311072 11437072 23923856
14:14:39 11437 8192000000 10633170768 11001933824 1690363536 17.104 100 80 160 2 1726592032 12907408 23321088
14:15:54 4310 8192000000 10694060960 11068145664 1757318000 17.662 100 92 182 1 1797503792 14329648 25856144
14:17:02 2743 8192000000 10740378320 11124375552 1807472352 18.075 100 85 170 1 1843271152 12272816 23525984
14:18:05 1223 8192000000 10800390720 11185029120 1859198992 18.497 100 96 196 1 1898830048 12572928 27058128
14:19:08 1697 8192000000 10811535664 11207442432 1875813392 18.631 100 77 154 1 1909814352 12937360 21063600
14:20:10 6243 8192000000 10798034064 11212357632 1877712480 18.647 100 41 82 1 1895134016 6152784 11268752
14:21:19 14745 8192000000 10794765376 11220176896 1881889952 18.680 100 44 88 2 1901724320 7176752 12657616
14:22:37 8348 8192000000 10788547824 11224211456 1885813328 18.712 100 20 40 3 1898309200 5889600 6606272
14:23:46 7985 8192000000 10803335440 11233796096 1891381392 18.757 100 17 34 0 1902158720 6073200 4704128
14:24:54 7677 8192000000 10812391120 11247861760 1903464496 18.854 100 23 47 0 1916504640 6632784 6407360
14:26:04 47435 8192000000 10788849856 11230216192 1862767600 18.526 98 34 64 2 1876111360 4659760 8684000
14:27:52 3036 8192000000 10822464688 11245002752 1905181280 18.868 100 72 148 1 1933068448 7367856 20519312
14:28:55 6173 8192000000 10749058048 11168305152 1783196720 17.876 96 31 63 0 1797688592 5511856 8980016
14:30:01 60945 8192000000 10744356528 11132411904 1676944944 16.992 91 59 120 0 1699120480 5511760 16663776
14:32:03 6712 8192000000 10730997168 11140251648 1794232688 17.967 99 28 56 1 1809059952 6135104 8692160
14:33:11 11592 8192000000 10749166176 11169284096 1796501808 17.985 98 32 62 3 1810839264 5602224 8735232
14:34:22 64574 8192000000 10755737552 11189469184 1849481504 18.418 100 27 54 2 1863076992 6379200 7216288
14:36:28 8233 8192000000 10791446912 11209170944 1864398128 18.539 100 22 44 1 1877703696 5750048 7555520
14:37:40 9924 8192000000 10827724864 11245355008 1897299488 18.805 100 21 42 2 1908146688 4598624 6248576
14:38:56 34945 8192000000 10864974016 11283464192 1930257376 19.069 100 56 112 2 1956242208 9912336 16072496
14:40:34 17255 8192000000 10858507936 11294957568 1934747328 19.105 100 31 60 2 1952254688 8870064 8637296
14:42:07 18729 8192000000 10890400752 11325108224 1939880864 19.146 99 47 92 1 1964443504 10843136 13719504
14:43:38 21440 8192000000 10895227936 11336011776 1934340384 19.102 98 44 88 1 1965611408 17550288 13720736
14:45:07 2398 8192000000 10938884176 11354791936 1982740992 19.486 100 77 154 2 2021243920 16810736 21692192
14:46:17 13904 8192000000 10933043712 11362516992 1984845280 19.503 100 64 124 1 2023720032 20640928 18233824
14:47:36 11999 8192000000 10947161072 11386826752 2007695296 19.683 100 53 105 3 2045199072 21079568 16424208
14:48:53 7256 8192000000 10983641936 11410653184 2026108480 19.828 100 66 132 1 2071116656 25086720 19921456
14:50:05 8987 8192000000 10937038944 11364712448 1960922624 19.313 97 66 132 3 2005223392 24327104 19973664
14:51:22 9086 8192000000 10957214128 11378020352 1985596800 19.509 99 71 140 0 2032805296 26927424 20281072
14:52:38 4100 8192000000 10944253920 11361218560 1976597056 19.438 100 76 151 0 2027331696 27475632 23259008
14:53:48 3518 8192000000 10946581376 11358953472 1944734320 19.185 98 79 157 0 1999731280 29652832 25344128
14:54:57 2472 8192000000 10954820544 11360100352 1971691904 19.399 100 92 177 3 2029112256 29898352 27522000
14:56:05 3885 8192000000 10912738304 11322626048 1895338048 18.789 98 68 132 1 1956158720 38793536 22027136
14:57:12 8744 8192000000 10883864496 11328208896 1930393184 19.070 100 34 68 1 1972727056 30349552 11984320
14:58:27 4490 8192000000 10912667232 11346644992 1948733520 19.216 100 48 96 2 1995690320 31203520 15753280
14:59:42 12294 8192000000 10938724816 11374972928 1973801408 19.416 100 40 80 1 2018179200 30915696 13462096
15:00:59 8422 8192000000 10938494896 11381055488 1977786464 19.447 100 30 60 0 2013272608 25486720 9999424
15:02:13 19098 8192000000 10924073808 11380822016 1979967024 19.464 100 29 58 1 2017069888 27781120 9321744
15:03:54 70194 8192000000 10892000288 11366789120 1933894688 19.098 99 12 24 0 1970120192 31861104 4364400
15:06:05 74750 8192000000 10881880368 11330600960 1890280432 18.748 97 8 16 0 1900185744 7633840 2271472
15:08:20 79484 8192000000 10907860960 11362254848 1959814544 19.305 100 11 22 1 1978986528 15644992 3526992
15:10:59 69126 8192000000 10887871600 11357061120 1928361152 19.054 98 9 17 0 1944925888 14644384 1920352
15:13:10 56314 8192000000 10908122048 11365351424 1952777456 19.249 99 9 18 0 1970639712 14614592 3247664
15:15:09 40742 8192000000 10911545712 11367333888 1965615728 19.351 99 46 93 2 1985204416 6014512 13574176
15:16:53 32585 8192000000 10902302192 11350384640 1933639248 19.096 98 40 80 0 1954540368 9803280 11097840
15:18:28 31157 8192000000 10912001200 11371425792 1979876224 19.464 100 20 40 4 1996172448 10970688 5325536
15:20:00 36690 8192000000 10953161728 11402510336 2004741152 19.660 100 40 76 3 2028977200 12492720 11743328
15:21:38 19715 8192000000 10955742960 11410636800 2017707696 19.762 100 53 106 1 2051887296 18239648 15939952
15:22:59 62730 8192000000 10942390224 11432054784 2014457808 19.737 100 17 33 0 2038575856 18585360 5532688
15:25:03 48100 8192000000 10918545088 11396812800 1979211920 19.458 99 10 20 1 1996382688 14206480 2964288
15:26:57 77600 8192000000 10921974400 11392090112 1955605840 19.271 98 18 36 0 1972313472 11121264 5586368
15:29:15 68106 8192000000 10925135776 11401281536 1983292240 19.491 99 7 14 1 1993637984 8261280 2084464
15:31:32 33424 8192000000 10912302112 11373060096 1920874256 18.994 97 19 36 2 1935284896 9690464 4720176
15:33:08 50929 8192000000 10865814880 11333414912 1871761040 18.599 95 21 42 1 1890081088 11936096 6383952
15:35:00 22529 8192000000 10879378848 11337039872 1913335840 18.933 98 41 81 1 1932305904 6640464 12329600
15:36:33 56614 8192000000 10862912816 11313700864 1841854336 18.356 95 23 46 0 1861415312 12945008 6615968
15:38:30 42413 8192000000 10822008336 11268370432 1739187776 17.512 90 21 42 1 1752123264 7750944 5184544
15:40:13 28484 8192000000 10885214336 11330961408 1929124848 19.060 100 26 52 1 1954984528 18003040 7856640
15:41:50 101461 8192000000 10862777648 11326177280 1924562208 19.023 99 31 62 1 1949103920 15251264 9290448
15:44:32 11321 8192000000 10852206336 11312500736 1913868256 18.938 99 17 34 0 1931241392 12757712 4615424
15:45:44 93560 8192000000 10868514208 11341787136 1948663152 19.216 100 21 42 0 1967038096 12141200 6233744
15:48:20 59529 8192000000 10877403488 11352969216 1912161472 18.924 98 14 28 1 1931175584 15547232 3466880
15:50:27 55793 8192000000 10862898256 11346718720 1904759776 18.865 98 15 30 1 1933430704 24180464 4490464
15:52:27 61115 8192000000 10860682928 11335471104 1881675984 18.679 97 13 26 1 1904476256 19004880 3795392
15:54:47 51209 8192000000 10875353680 11322417152 1900061056 18.827 98 37 74 1 1919440240 8787376 10591808
15:56:45 82042 8192000000 10867052496 11340333056 1892063200 18.762 97 23 46 1 1910888656 12086784 6738672
15:59:07 56479 8192000000 10876335376 11336572928 1886746624 18.720 97 27 54 3 1903862784 9884144 7232016
16:01:10 91673 8192000000 10877501840 11344605184 1892520928 18.766 97 20 40 0 1917231600 18964736 5745936
16:03:43 96155 8192000000 10888625120 11365449728 1935576048 19.111 99 17 32 2 1960023360 19957728 4489584
16:06:20 28077 8192000000 10920670848 11379073024 1964403264 19.341 100 26 52 1 1986505488 14727632 7374592
16:07:50 91940 8192000000 10900480096 11372371968 1974208704 19.419 100 29 58 1 1997294048 14545056 8540288
16:10:25 47492 8192000000 10875294096 11352199168 1930162224 19.068 98 36 72 0 1953189168 13120416 9906528
16:12:13 33580 8192000000 10877826960 11365679104 1949819312 19.225 99 4 8 0 1957212960 6683600 710048
16:13:47 53549 8192000000 10898609184 11383087104 1980519232 19.469 100 10 19 1 1991426240 8197712 2709296
16:15:45 30409 8192000000 10882421584 11355529216 1898149120 18.811 96 13 24 1 1908769664 6756288 3864256
16:17:21 43838 8192000000 10893312368 11373424640 1959530400 19.302 100 21 42 1 1980152720 14726880 5895440
16:19:06 56603 8192000000 10905657680 11370782720 1915569680 18.951 97 19 38 1 1934940384 14257728 5112976
16:21:03 55266 8192000000 10859832240 11334438912 1877985920 18.649 96 22 44 3 1897851152 13066672 6798560
16:23:19 42824 8192000000 10877000912 11344568320 1896978304 18.802 97 24 48 2 1917266560 13024240 7264016
16:25:01 38560 8192000000 10873293904 11331768320 1909041280 18.899 99 35 70 0 1931277424 12746128 9490016
16:26:40 24754 8192000000 10852985072 11314163712 1879288992 18.659 98 32 64 0 1903130880 14960160 8881728
16:28:26 142438 8192000000 10818883472 11271401472 1806668496 18.069 94 36 72 0 1828159744 11537712 9953536
16:31:50 54840 8192000000 10847760320 11326595072 1926049312 19.035 100 15 30 1 1939086640 9008256 4029072
16:33:49 86265 8192000000 10891115760 11378196480 1952838592 19.249 100 14 28 0 1977455520 19954192 4662736
16:36:15 71780 8192000000 10897475824 11379441664 1961462032 19.318 100 10 20 1 1984401728 20140128 2799568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-09-2021 16:39:49 to 04-09-2021 16:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-09-2021 16:40:05 to 04-09-2021 16:40:07.

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          243985
MON$OLDEST_ACTIVE               246601
MON$OLDEST_SNAPSHOT             246601
MON$NEXT_TRANSACTION            246601
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                       2032544
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 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 ---
:122: 
:123: mscs-test-161.budzdorov.ru    Sat Sep  4 16:38:00 2021
:124:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:125: 
:126: 
:127: mscs-test-161.budzdorov.ru    Sat Sep  4 16:38:00 2021
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:129: 
:130: 
:131: mscs-test-161.budzdorov.ru    Sat Sep  4 16:38:03 2021
:132:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:133: 
:134: 
:135: mscs-test-161.budzdorov.ru    Sat Sep  4 16:38:03 2021
:136:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:137: 
--- end of diff output ---

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

04.09.21 16:40:08. 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
2021-09-04 16:40:11 No severe PSQL-related problems occured
04.09.21 16:40:12. End of report.