Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.09.2021 19:38
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 15894640 kB
MemAvailable 30846664 kB
Buffers 0 kB
Cached 15746708 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-05 19:37:45
DTS_END                         2021-09-05 19:37:45.7870
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/54912
TRN_ID                          244440
ATT_ID                          726
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: 9642.91 18733 1157149
customer order: creation 179.58 378 21550
customer order: refuse 15.11 338 1813
order to supplier: creation 47.39 1374 5687
order to supplier: removal 3.38 127803 405
invoice (draft): creation 46.86 3608 5623
invoice (draft): removal 54.33 1025 6519
invoice accept: apply 42.62 19733 5114
invoice accept: cancel 49.73 876 5967
customer reserve: creation 4005.54 191 480665
customer reserve: removal 4887.31 67 586477
realization accept: apply 85.18 181 10222
realization accept: cancel 92.18 118 11061
payment from customer: creation 60.83 141 7300
payment from customer: removal 5.76 73 691
payment to supplier: creation 50.85 163 6102
payment to supplier: removal 3.58 105 429
service: total inventory turnovers 4.80 1156 576
service: total monetary turnovers 6.92 33 830
service: aggregate perf. data 0.95 17182 114
service: refresh index statistics 0.03 200121 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-09-2021 19:38:56 to 05-09-2021 19:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3134
TEST_TIME 2 2650
TEST_TIME 3 2872
TEST_TIME 4 3616
TEST_TIME 5 3989
TEST_TIME 6 4402
TEST_TIME 7 5027
TEST_TIME 8 5287
TEST_TIME 9 6049
TEST_TIME 10 6152
TEST_TIME 11 6466
TEST_TIME 12 6592
TEST_TIME 13 6885
TEST_TIME 14 7011
TEST_TIME 15 7285
TEST_TIME 16 7506
TEST_TIME 17 7613
TEST_TIME 18 7587
TEST_TIME 19 7523
TEST_TIME 20 7786
TEST_TIME 21 7885
TEST_TIME 22 8272
TEST_TIME 23 8218
TEST_TIME 24 8201
TEST_TIME 25 8608
TEST_TIME 26 8803
TEST_TIME 27 8973
TEST_TIME 28 9037
TEST_TIME 29 9617
TEST_TIME 30 10113
TEST_TIME 31 11340
TEST_TIME 32 11670
TEST_TIME 33 11493
TEST_TIME 34 11298
TEST_TIME 35 11255
TEST_TIME 36 11092
TEST_TIME 37 11157
TEST_TIME 38 11032
TEST_TIME 39 10987
TEST_TIME 40 10767
TEST_TIME 41 10769
TEST_TIME 42 10673
TEST_TIME 43 10573
TEST_TIME 44 10659
TEST_TIME 45 10633
TEST_TIME 46 10532
TEST_TIME 47 10440
TEST_TIME 48 10425
TEST_TIME 49 10451
TEST_TIME 50 10337
TEST_TIME 51 10265
TEST_TIME 52 10260
TEST_TIME 53 10225
TEST_TIME 54 10265
TEST_TIME 55 10229
TEST_TIME 56 10288
TEST_TIME 57 10370
TEST_TIME 58 10406
TEST_TIME 59 10386
TEST_TIME 60 10507
TEST_TIME 61 10423
TEST_TIME 62 10444
TEST_TIME 63 10385
TEST_TIME 64 10256
TEST_TIME 65 10227
TEST_TIME 66 10243
TEST_TIME 67 10157
TEST_TIME 68 10118
TEST_TIME 69 10069
TEST_TIME 70 10012
TEST_TIME 71 9984
TEST_TIME 72 10016
TEST_TIME 73 9998
TEST_TIME 74 10052
TEST_TIME 75 9986
TEST_TIME 76 9967
TEST_TIME 77 10028
TEST_TIME 78 9978
TEST_TIME 79 9978
TEST_TIME 80 10062
TEST_TIME 81 10038
TEST_TIME 82 10020
TEST_TIME 83 9991
TEST_TIME 84 10028
TEST_TIME 85 10149
TEST_TIME 86 10099
TEST_TIME 87 10012
TEST_TIME 88 9989
TEST_TIME 89 9925
TEST_TIME 90 9935
TEST_TIME 91 9904
TEST_TIME 92 9831
TEST_TIME 93 9783
TEST_TIME 94 9740
TEST_TIME 95 9695
TEST_TIME 96 9809
TEST_TIME 97 9847
TEST_TIME 98 9847
TEST_TIME 99 9852
TEST_TIME 100 9834
TEST_TIME 101 9826
TEST_TIME 102 9810
TEST_TIME 103 9798
TEST_TIME 104 9806
TEST_TIME 105 9864
TEST_TIME 106 9862
TEST_TIME 107 9885
TEST_TIME 108 9891
TEST_TIME 109 9804
TEST_TIME 110 9826
TEST_TIME 111 9785
TEST_TIME 112 9775
TEST_TIME 113 9730
TEST_TIME 114 9742
TEST_TIME 115 9695
TEST_TIME 116 9693
TEST_TIME 117 9676
TEST_TIME 118 9652
TEST_TIME 119 9666
TEST_TIME 120 9646

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-09-2021 19:39:00 to 05-09-2021 19:39:03.

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 1600681 1600681 0 0.00 0 24867 7 0 0
doc_list_biud 1600680 1600680 0 0.00 0 49893 31 0 0
sp_add_invoice_to_stock 5252 5114 138 2.62 60 217218 19734 0 138
sp_cancel_adding_invoice 5967 5967 0 0.00 5 70444 876 0 0
sp_cancel_client_order 1813 1813 0 0.00 4 9871 339 0 0
sp_cancel_customer_reserve 586477 586477 0 0.00 1 49895 67 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 4669 74 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 2 6067 106 0 0
sp_cancel_supplier_invoice 6529 6519 10 0.15 2 21164 1026 0 10
sp_cancel_supplier_order 405 405 0 0.00 8 1179784 127803 0 0
sp_cancel_write_off 11061 11061 0 0.00 1 15277 119 0 0
sp_client_order 21550 21550 0 0.00 4 27326 379 0 0
sp_customer_reserve 480665 480665 0 0.00 0 80908 192 0 0
sp_fill_shopping_cart 32858 32858 0 0.00 1 13322 42 0 0
sp_fill_shopping_cart_clo_res 480662 480662 0 0.00 0 12701 13 0 0
sp_get_clo_for_invoice 5109 5109 0 0.00 0 23985 298 0 0
sp_kill_cost_storno 18148 18148 0 0.00 0 19865 65 0 0
sp_kill_qstorno_ret_qs2qd 1190939 1190939 0 0.00 0 49887 37 0 0
sp_kill_qty_storno 627573 627573 0 0.00 0 49893 79 0 0
sp_lock_selected_doc 21214 21214 0 0.00 0 6656 4 0 0
sp_make_cost_storno 28733 28733 0 0.00 0 23362 55 0 0
sp_multiply_rows_for_pdistr 28733 28733 0 0.00 0 24124 33 0 0
sp_multiply_rows_for_qdistr 21550 21550 0 0.00 0 12873 161 0 0
sp_pay_from_customer 7300 7300 0 0.00 4 17317 142 0 0
sp_pay_to_supplier 6102 6102 0 0.00 5 25063 164 0 0
sp_payment_common 13402 13402 0 0.00 1 22640 39 0 0
sp_qd_handle_on_cancel_clo 1813 1813 0 0.00 0 9863 308 0 0
sp_qd_handle_on_invoice_upd_sts 11076 11076 0 0.00 0 18863 388 0 0
sp_qd_handle_on_reserve_upd_sts 10222 10222 0 0.00 0 17716 62 0 0
sp_reserve_write_off 10222 10222 0 0.00 7 23392 182 0 0
sp_supplier_invoice 5623 5623 0 0.00 8 90004 3609 0 0
sp_supplier_order 5687 5687 0 0.00 5 58500 1374 0 0
srv_aggregate_perf_data 114 114 0 0.00 3268 112105 17182 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 1167 120260 38570 0 0
srv_find_qd_qs_mism 1106868 1106868 0 0.00 0 12094 6 0 0
srv_make_invnt_saldo 576 576 0 0.00 38 79955 1156 0 0
srv_make_money_saldo 830 830 0 0.00 1 2044 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 101669 315841 200122 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5114 5114 0 0.00 0 23828 211 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 11220 123 0 0
t$perf-norm:sp_cancel_client_order 1813 1813 0 0.00 0 5200 17 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 3323 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 2793 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 470 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 4081 30 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 24953 1917 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 1667 7 0 0
t$perf-norm:sp_client_order 21550 21550 0 0.00 0 8709 22 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 4572 12 0 0
t$perf-norm:sp_pay_from_customer 7300 7300 0 0.00 0 5954 9 0 0
t$perf-norm:sp_pay_to_supplier 6102 6102 0 0.00 0 7997 12 0 0
t$perf-norm:sp_reserve_write_off 10222 10222 0 0.00 0 14308 13 0 0
t$perf-norm:sp_supplier_invoice 5623 5623 0 0.00 0 8326 30 0 0
t$perf-norm:sp_supplier_order 5687 5687 0 0.00 0 6370 17 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 415 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 290 5 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 1422 29 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 1220 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10494 10218 276 2.63 0 7245 25 0 276
v_all_customers 39778 39778 0 0.00 0 19 1 0 0
v_all_suppliers 31444 31444 0 0.00 0 35 1 0 0
v_all_wares 43100 43100 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 4912 13 0 0
v_cancel_client_order 3626 3626 0 0.00 0 5692 8 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 4220 11 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 1886 5 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 5548 14 0 0
v_cancel_supplier_invoice 848 828 20 2.35 0 270 14 0 20
v_cancel_supplier_order 810 810 0 0.00 0 1054 6 0 0
v_cancel_write_off 1972 1972 0 0.00 0 1607 15 0 0
v_max_id_clo_ord 5687 5687 0 0.00 0 2424 2 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 3824 14 0 0
v_max_id_ord_sup 5621 5621 0 0.00 0 7186 3 0 0
v_max_non_paid_invoice 1688 1688 0 0.00 0 838 3 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 3064 5 0 0
v_min_id_clo_ord 5687 5687 0 0.00 0 4017 9 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 4344 13 0 0
v_min_id_ord_sup 5621 5621 0 0.00 0 7374 9 0 0
v_min_non_paid_invoice 6102 6102 0 0.00 0 22624 27 0 0
v_min_non_paid_realizn 7300 7300 0 0.00 0 16202 30 0 0
v_our_firm 10630 10630 0 0.00 0 14 1 0 0
v_reserve_write_off 20444 20444 0 0.00 0 6915 8 0 0
x_lock_depdocs_on_canc_invoice 12072 12072 0 0.00 0 15364 144 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 16205 513 0 0
x_make_qstorno_1000_1200 5687 5687 0 0.00 1 58487 1283 0 0
x_make_qstorno_1200_2000 5623 5623 0 0.00 3 82473 3456 0 0
x_make_qstorno_2100_3300 480665 480665 0 0.00 0 80907 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-09-2021 19:39:04 to 05-09-2021 19:39:08.

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:07:49 10 8192000000 8802897616 8814821376 7256432 0.088 2 1 2 0 7375808 56816 62560
17:08:50 24749 8192000000 10367902896 10688647168 1418551728 14.760 100 85 170 0 1452149600 5837264 27760608
17:10:20 15647 8192000000 10442711520 10775683072 1501317088 15.488 100 90 180 1 1539252928 8141664 29794176
17:11:48 23985 8192000000 10506137824 10847608832 1542159488 15.842 100 83 166 1 1577819488 9666096 25993904
17:13:27 11974 8192000000 10585417424 10945888256 1643879584 16.713 100 76 152 0 1677466032 10966080 22620368
17:14:43 6043 8192000000 10656607904 11025391616 1711930624 17.285 100 80 159 1 1746135136 11542704 22661808
17:15:56 4417 8192000000 10728221376 11101229056 1790832128 17.939 100 89 179 1 1828982256 13434512 24715616
17:17:03 4064 8192000000 10756256688 11146129408 1825175328 18.220 100 77 150 2 1859404432 13127072 21102032
17:18:10 2450 8192000000 10816866432 11203960832 1875197328 18.626 100 95 190 1 1916028144 14821264 26009552
17:19:14 9743 8192000000 10834337056 11227086848 1891307344 18.756 100 77 152 1 1925730464 13363008 21060112
17:20:26 15687 8192000000 10799469168 11228057600 1889033840 18.738 100 44 85 4 1914925296 13602640 12288816
17:21:47 7446 8192000000 10800508416 11235921920 1899506544 18.822 100 23 45 1 1913403792 7015888 6881360
17:22:55 12232 8192000000 10811411712 11246465024 1904475248 18.862 100 16 32 0 1923036752 14968048 3593456
17:24:08 10599 8192000000 10831121824 11261095936 1918459616 18.974 100 21 42 0 1934778224 10211648 6106960
17:25:19 42560 8192000000 10831533200 11275018240 1924688736 19.024 100 34 67 1 1940123040 5561280 9873024
17:27:12 7046 8192000000 10810755632 11243589632 1885434560 18.709 99 19 37 3 1899981856 9781168 4766128
17:28:24 12373 8192000000 10772432480 11206119424 1811870672 18.111 96 17 34 1 1822449264 5689280 4889312
17:29:39 9625 8192000000 10752102592 11173371904 1827621024 18.240 99 33 66 1 1848231488 10368832 10241632
17:30:49 18409 8192000000 10715008864 11140231168 1709875328 17.268 93 22 44 1 1721359488 5710480 5773680
17:32:09 6161 8192000000 10701982720 11125653504 1780009104 17.850 99 20 39 1 1791325136 6089424 5226608
17:33:15 6959 8192000000 10733940848 11156856832 1818947360 18.169 100 20 40 1 1830554640 5548864 6058416
17:34:25 65055 8192000000 10770953040 11195645952 1853564736 18.451 100 33 66 1 1868328928 5230384 9533808
17:36:30 9597 8192000000 10776795184 11209244672 1869052000 18.577 100 28 56 3 1883291312 5248400 8990912
17:37:40 21976 8192000000 10813956688 11244883968 1898961392 18.818 100 18 36 2 1908703536 4938560 4803584
17:39:14 64987 8192000000 10855869328 11294531584 1937463104 19.127 100 42 82 1 1959688992 10472528 11753360
17:41:29 13133 8192000000 10883062640 11314511872 1947056432 19.203 100 43 85 1 1974292784 15035744 12200608
17:42:48 13688 8192000000 10904862912 11327197184 1963752464 19.336 100 47 91 3 1994091440 17077616 13261360
17:44:13 10927 8192000000 10914684976 11343450112 1970375568 19.388 100 60 119 0 2007580688 19784528 17420592
17:45:27 7197 8192000000 10936676528 11366797312 1985785696 19.510 100 59 118 1 2024846064 21676288 17384080
17:46:53 4517 8192000000 10931165472 11365769216 1979664816 19.462 100 44 85 1 2019423536 26790544 12968176
17:48:03 10029 8192000000 10937016416 11371606016 1989837232 19.543 100 55 105 2 2028763344 22699072 16227040
17:49:21 2745 8192000000 10946312384 11361615872 1981662944 19.478 100 83 166 2 2034522000 27821408 25037648
17:50:27 4563 8192000000 10969347920 11383132160 1994941760 19.583 100 83 166 2 2051790064 33141952 23706352
17:51:36 1664 8192000000 10967703472 11384889344 1991357824 19.555 100 92 183 0 2055558176 35065248 29135104
17:52:43 2921 8192000000 10954074272 11373641728 1971500912 19.397 99 83 166 0 2033707440 36543456 25663072
17:53:53 3984 8192000000 10982055520 11403239424 2010157344 19.703 100 76 150 0 2071911888 38915264 22839280
17:55:03 5194 8192000000 10983415424 11394330624 1997443872 19.603 100 86 172 2 2065189088 41346384 26398832
17:56:14 3791 8192000000 11003579344 11419131904 2004234320 19.656 100 84 167 0 2079886544 49934032 25718192
17:57:25 2256 8192000000 11006094576 11422052352 1996493248 19.595 100 78 156 2 2083940688 61990032 25457408
17:58:33 1164 8192000000 11024407264 11429658624 2011380848 19.712 100 93 185 2 2101627216 61010128 29236240
17:59:40 6613 8192000000 10996728112 11436158976 2007992416 19.686 100 46 92 2 2084050672 60807520 15250736
18:00:49 6093 8192000000 10993326624 11416256512 1995712064 19.589 100 64 127 1 2080502816 63927408 20863344
18:02:02 18258 8192000000 11014620288 11445485568 1973187856 19.411 98 45 88 1 2066337488 77180128 15969504
18:03:35 15176 8192000000 10986586480 11433865216 1985291728 19.507 100 41 82 1 2074570192 74549200 14729264
18:05:10 16089 8192000000 10981190288 11430526976 1964967520 19.346 100 30 60 4 2059322224 82615584 11739120
18:06:48 30758 8192000000 10966555216 11429584896 1949795376 19.225 100 27 54 1 2055594688 95104240 10695072
18:08:22 55744 8192000000 10912373552 11373977600 1974272096 19.419 100 27 53 1 2018946816 36759712 7915008
18:10:19 28521 8192000000 10925090800 11367739392 1966095600 19.354 99 38 73 1 1982564832 7260080 9209152
18:11:49 65754 8192000000 10910968064 11388870656 1980248832 19.467 100 28 56 2 2004210240 15776208 8185200
18:13:56 120260 8192000000 10923609840 11361771520 1945894576 19.194 98 17 34 1 1961347072 10756000 4696496
18:17:01 17738 8192000000 10907662720 11369873408 1959911392 19.305 99 24 46 0 1977592240 11581904 6098944
18:18:33 19952 8192000000 10911183664 11378774016 1986493040 19.516 100 18 37 1 2004159904 12400992 5265872
18:19:54 31580 8192000000 10929715184 11399712768 1990522992 19.548 99 25 50 1 2008001648 10293888 7184768
18:21:27 43663 8192000000 10915935968 11404107776 1961730960 19.320 98 14 28 1 1984050656 18483360 3836336
18:23:41 38178 8192000000 10912221632 11387473920 1965209440 19.347 99 8 16 0 1980355376 12969584 2176352
18:25:19 25059 8192000000 10905752032 11361390592 1927965520 19.051 97 38 73 2 1953870544 15612112 10292912
18:26:44 38566 8192000000 10875072736 11363758080 1959884416 19.305 100 5 10 1 1970611952 9690800 1036736
18:28:28 41066 8192000000 10907239872 11383472128 1979485616 19.461 100 24 49 0 1994092528 7791072 6815840
18:30:11 82333 8192000000 10912907632 11394842624 1934033264 19.099 97 11 22 1 1952895744 15283424 3579056
18:32:52 99950 8192000000 10896198608 11370844160 1916467936 18.959 97 14 28 0 1940621392 19727328 4426128
18:35:36 53247 8192000000 10884934096 11347025920 1909277232 18.901 97 18 36 1 1935960064 21208864 5473968
18:37:29 53417 8192000000 10891078000 11349192704 1888722336 18.735 96 6 13 0 1898932736 8519936 1690464
18:39:24 31969 8192000000 10895265584 11347951616 1923817840 19.017 98 30 59 1 1942706640 10743024 8145776
18:40:59 110388 8192000000 10876103888 11350171648 1923300816 19.013 98 26 52 0 1947356784 16116448 7939520
18:44:16 11645 8192000000 10896211776 11354198016 1921082272 18.996 98 26 52 1 1940711712 12503680 7125760
18:45:28 60448 8192000000 10900663008 11367903232 1944301856 19.181 98 23 46 0 1967062832 15947920 6813056
18:48:03 60306 8192000000 10883422736 11365974016 1932391616 19.086 98 15 30 0 1952792496 16153648 4247232
18:50:14 30462 8192000000 10862719392 11346976768 1915121728 18.948 98 15 29 0 1940820592 21739712 3959152
18:51:49 48618 8192000000 10882817168 11329060864 1888500736 18.734 97 24 48 1 1904895952 9896928 6498288
18:53:40 75009 8192000000 10864111888 11346264064 1918308752 18.973 99 18 36 0 1937049632 13649440 5091440
18:55:56 40753 8192000000 10865963248 11320934400 1896013104 18.794 98 31 62 1 1917906960 13069088 8824768
18:57:55 68484 8192000000 10864008640 11331547136 1868564544 18.573 96 23 46 2 1889351376 14263616 6523216
19:00:11 13611 8192000000 10901363680 11352616960 1951319024 19.237 100 41 82 1 1974319216 11549968 11450224
19:01:28 21637 8192000000 10913317440 11373260800 1954042400 19.259 99 42 84 1 1983192432 17493264 11656768
19:03:01 77229 8192000000 10899149328 11375468544 1955913600 19.274 99 18 33 2 1973763072 13647888 4201584
19:05:28 78737 8192000000 10905854816 11383517184 1977547488 19.445 100 18 36 2 1991596592 9507264 4541840
19:07:50 75686 8192000000 10910168480 11401465856 1976763888 19.439 99 12 24 0 1988321648 8882992 2674768
19:10:06 73322 8192000000 10937496496 11420135424 1985108416 19.505 99 11 22 1 2003220352 14949456 3162480
19:12:34 32478 8192000000 10922869568 11417243648 1954453440 19.262 97 33 67 3 1987376816 22303488 10619888
19:14:22 50875 8192000000 10947870864 11421999104 1990981952 19.552 100 17 34 0 2007266592 11469280 4815360
19:16:16 65879 8192000000 10904763696 11384074240 1950673088 19.232 98 28 55 1 1971842448 13037632 8131728
19:18:25 49624 8192000000 10883809248 11335229440 1795927616 17.980 90 39 76 1 1815112912 7616688 11568608
19:20:15 26903 8192000000 10883203712 11344576512 1900419792 18.830 97 30 60 1 1920720192 11679168 8621232
19:21:47 48745 8192000000 10884763808 11358429184 1926914880 19.042 98 22 44 0 1945116896 11962352 6239664
19:23:37 54697 8192000000 10881022880 11361013760 1896461248 18.798 96 14 28 0 1913713616 13400048 3852320
19:25:32 108100 8192000000 10871075008 11347181568 1916594880 18.960 98 24 47 1 1940787232 17279712 6912640
19:28:22 29802 8192000000 10874282192 11349229568 1949926848 19.226 100 22 44 0 1964251280 8291808 6032624
19:29:52 43721 8192000000 10854580704 11341684736 1920215392 18.989 98 9 18 1 1933824640 10960400 2648848
19:31:40 83533 8192000000 10872010640 11360690176 1908896624 18.898 97 10 21 0 1926564592 14667840 3000128
19:34:05 50724 8192000000 10867394768 11347636224 1878687936 18.655 96 14 28 1 1894953472 12536240 3729296
19:35:56 40401 8192000000 10868108576 11338186752 1920497920 18.991 98 24 47 1 1935411600 7983488 6930192
19:37:37 14645 8192000000 9170583168 9418481664 330479792 3.877 18 3 6 0 336346880 5049952 817136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 05-09-2021 19:39:10 to 05-09-2021 19:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-09-2021 19:39:26 to 05-09-2021 19:39:28.

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          246526
MON$OLDEST_ACTIVE               246527
MON$OLDEST_SNAPSHOT             246527
MON$NEXT_TRANSACTION            246527
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                       2051744
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 ---
:215: 
:216: mscs-test-161.budzdorov.ru    Sun Sep  5 19:38:00 2021
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:218: 
--- end of diff output ---

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

05.09.21 19:39:29. 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-05 19:39:32 No severe PSQL-related problems occured
05.09.21 19:39:33. End of report.