Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.08.2021 16: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 15945228 kB
MemAvailable 30907048 kB
Buffers 0 kB
Cached 15715900 kB
SwapCached 324 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-08-29 16:37:48
DTS_END                         2021-08-29 16:37:48.4870
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/54522
TRN_ID                          237893
ATT_ID                          663
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: 9576.21 24805 1149145
customer order: creation 169.97 363 20396
customer order: refuse 14.11 384 1693
order to supplier: creation 44.98 1337 5398
order to supplier: removal 3.57 98319 428
invoice (draft): creation 45.39 3450 5447
invoice (draft): removal 56.53 697 6784
invoice accept: apply 41.80 18513 5016
invoice accept: cancel 51.54 791 6185
customer reserve: creation 3881.54 182 465785
customer reserve: removal 4962.57 55 595508
realization accept: apply 84.52 173 10142
realization accept: cancel 89.98 109 10798
payment from customer: creation 59.79 120 7175
payment from customer: removal 5.94 103 713
payment to supplier: creation 48.29 136 5795
payment to supplier: removal 3.42 79 410
service: total inventory turnovers 4.82 1217 578
service: total monetary turnovers 6.62 38 794
service: aggregate perf. data 0.80 25641 96
service: refresh index statistics 0.03 344392 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-08-2021 16:39:05 to 29-08-2021 16:39:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2276
TEST_TIME 2 4584
TEST_TIME 3 4702
TEST_TIME 4 6024
TEST_TIME 5 6704
TEST_TIME 6 7181
TEST_TIME 7 7302
TEST_TIME 8 6960
TEST_TIME 9 6781
TEST_TIME 10 6811
TEST_TIME 11 7134
TEST_TIME 12 7487
TEST_TIME 13 7351
TEST_TIME 14 7289
TEST_TIME 15 7178
TEST_TIME 16 7448
TEST_TIME 17 7847
TEST_TIME 18 8125
TEST_TIME 19 8114
TEST_TIME 20 7968
TEST_TIME 21 8513
TEST_TIME 22 8436
TEST_TIME 23 8448
TEST_TIME 24 8560
TEST_TIME 25 8941
TEST_TIME 26 8955
TEST_TIME 27 9295
TEST_TIME 28 9466
TEST_TIME 29 9544
TEST_TIME 30 9468
TEST_TIME 31 9612
TEST_TIME 32 10420
TEST_TIME 33 10591
TEST_TIME 34 10899
TEST_TIME 35 10811
TEST_TIME 36 10821
TEST_TIME 37 10633
TEST_TIME 38 10473
TEST_TIME 39 10477
TEST_TIME 40 10625
TEST_TIME 41 10488
TEST_TIME 42 10548
TEST_TIME 43 10505
TEST_TIME 44 10269
TEST_TIME 45 10175
TEST_TIME 46 10278
TEST_TIME 47 10183
TEST_TIME 48 10059
TEST_TIME 49 10023
TEST_TIME 50 9927
TEST_TIME 51 9870
TEST_TIME 52 9801
TEST_TIME 53 9987
TEST_TIME 54 10050
TEST_TIME 55 10086
TEST_TIME 56 10151
TEST_TIME 57 10073
TEST_TIME 58 9929
TEST_TIME 59 9966
TEST_TIME 60 9868
TEST_TIME 61 9768
TEST_TIME 62 9844
TEST_TIME 63 9719
TEST_TIME 64 9724
TEST_TIME 65 9712
TEST_TIME 66 9740
TEST_TIME 67 9709
TEST_TIME 68 9778
TEST_TIME 69 9793
TEST_TIME 70 9906
TEST_TIME 71 9904
TEST_TIME 72 9884
TEST_TIME 73 9883
TEST_TIME 74 9931
TEST_TIME 75 9934
TEST_TIME 76 9980
TEST_TIME 77 10035
TEST_TIME 78 9963
TEST_TIME 79 9884
TEST_TIME 80 9855
TEST_TIME 81 9790
TEST_TIME 82 9800
TEST_TIME 83 9788
TEST_TIME 84 9777
TEST_TIME 85 9723
TEST_TIME 86 9672
TEST_TIME 87 9601
TEST_TIME 88 9575
TEST_TIME 89 9612
TEST_TIME 90 9596
TEST_TIME 91 9632
TEST_TIME 92 9691
TEST_TIME 93 9667
TEST_TIME 94 9678
TEST_TIME 95 9751
TEST_TIME 96 9793
TEST_TIME 97 9830
TEST_TIME 98 9824
TEST_TIME 99 9802
TEST_TIME 100 9903
TEST_TIME 101 9885
TEST_TIME 102 9945
TEST_TIME 103 9917
TEST_TIME 104 9848
TEST_TIME 105 9810
TEST_TIME 106 9766
TEST_TIME 107 9776
TEST_TIME 108 9791
TEST_TIME 109 9741
TEST_TIME 110 9712
TEST_TIME 111 9697
TEST_TIME 112 9667
TEST_TIME 113 9616
TEST_TIME 114 9636
TEST_TIME 115 9618
TEST_TIME 116 9579
TEST_TIME 117 9598
TEST_TIME 118 9600
TEST_TIME 119 9606
TEST_TIME 120 9580

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-08-2021 16:39:09 to 29-08-2021 16:39:12.

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 1580511 1580511 0 0.00 0 33039 6 0 0
doc_list_biud 1580510 1580510 0 0.00 0 39911 26 0 0
sp_add_invoice_to_stock 5144 5016 128 2.48 27 170453 18514 0 128
sp_cancel_adding_invoice 6185 6185 0 0.00 4 54374 792 0 0
sp_cancel_client_order 1693 1693 0 0.00 4 15524 385 0 0
sp_cancel_customer_reserve 595508 595508 0 0.00 1 22598 56 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 7763 103 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 5244 79 0 0
sp_cancel_supplier_invoice 6794 6784 10 0.14 1 39918 697 0 10
sp_cancel_supplier_order 428 428 0 0.00 14 1001444 98319 0 0
sp_cancel_write_off 10798 10798 0 0.00 1 18426 109 0 0
sp_client_order 20396 20396 0 0.00 4 23975 363 0 0
sp_customer_reserve 465785 465785 0 0.00 0 105363 182 0 0
sp_fill_shopping_cart 31211 31211 0 0.00 1 8933 40 0 0
sp_fill_shopping_cart_clo_res 465778 465778 0 0.00 0 17624 12 0 0
sp_get_clo_for_invoice 5009 5009 0 0.00 0 25586 268 0 0
sp_kill_cost_storno 18106 18106 0 0.00 0 18423 56 0 0
sp_kill_qstorno_ret_qs2qd 1209026 1209026 0 0.00 0 39908 30 0 0
sp_kill_qty_storno 636547 636547 0 0.00 0 39909 65 0 0
sp_lock_selected_doc 20890 20890 0 0.00 0 9378 6 0 0
sp_make_cost_storno 28121 28121 0 0.00 0 20381 56 0 0
sp_multiply_rows_for_pdistr 28121 28121 0 0.00 0 7992 29 0 0
sp_multiply_rows_for_qdistr 20395 20395 0 0.00 0 8797 154 0 0
sp_pay_from_customer 7175 7175 0 0.00 4 18517 120 0 0
sp_pay_to_supplier 5795 5795 0 0.00 5 18796 137 0 0
sp_payment_common 12970 12970 0 0.00 2 12505 31 0 0
sp_qd_handle_on_cancel_clo 1693 1693 0 0.00 0 12772 313 0 0
sp_qd_handle_on_invoice_upd_sts 11194 11194 0 0.00 0 29904 331 0 0
sp_qd_handle_on_reserve_upd_sts 10142 10142 0 0.00 0 10162 51 0 0
sp_reserve_write_off 10142 10142 0 0.00 6 11050 174 0 0
sp_supplier_invoice 5447 5447 0 0.00 6 80322 3451 0 0
sp_supplier_order 5398 5398 0 0.00 6 34663 1338 0 0
srv_aggregate_perf_data 96 96 0 0.00 2436 645026 25642 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2727 122422 44811 0 0
srv_find_qd_qs_mism 1101501 1101501 0 0.00 0 33038 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 32 60143 1218 0 0
srv_make_money_saldo 794 794 0 0.00 1 6561 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 189984 419692 344393 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 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 5016 5016 0 0.00 0 13445 184 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 2581 85 0 0
t$perf-norm:sp_cancel_client_order 1693 1693 0 0.00 0 1137 11 0 0
t$perf-norm:sp_cancel_customer_reserve 679 679 0 0.00 0 1271 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 5032 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 1316 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 791 14 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 21320 1621 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 1623 10 0 0
t$perf-norm:sp_client_order 20396 20396 0 0.00 0 5595 18 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 5456 19 0 0
t$perf-norm:sp_pay_from_customer 7175 7175 0 0.00 0 7465 9 0 0
t$perf-norm:sp_pay_to_supplier 5795 5795 0 0.00 0 3774 9 0 0
t$perf-norm:sp_reserve_write_off 10142 10142 0 0.00 0 6632 12 0 0
t$perf-norm:sp_supplier_invoice 5447 5447 0 0.00 0 5824 23 0 0
t$perf-norm:sp_supplier_order 5398 5398 0 0.00 0 8247 19 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 112 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 118 3 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 3137 28 0 0
t$perf-norm:srv_make_money_saldo 794 794 0 0.00 0 2338 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 5 0 0
v_add_invoice_to_stock 10274 10018 256 2.49 0 16306 22 0 256
v_all_customers 37126 37126 0 0.00 0 19 1 0 0
v_all_suppliers 29864 29864 0 0.00 0 21 1 0 0
v_all_wares 40790 40790 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 5435 16 0 0
v_cancel_client_order 3386 3386 0 0.00 0 15493 25 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 6518 14 0 0
v_cancel_customer_reserve 1358 1358 0 0.00 0 9830 14 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 4182 8 0 0
v_cancel_supplier_invoice 858 838 20 2.33 0 3085 20 0 20
v_cancel_supplier_order 856 856 0 0.00 0 1631 10 0 0
v_cancel_write_off 1966 1966 0 0.00 0 2873 17 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 2570 2 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 1701 11 0 0
v_max_id_ord_sup 5418 5418 0 0.00 0 5530 4 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 5146 12 0 0
v_max_non_paid_realizn 3904 3904 0 0.00 0 4168 5 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 7109 14 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 2574 6 0 0
v_min_id_ord_sup 5418 5418 0 0.00 0 5207 8 0 0
v_min_non_paid_invoice 5795 5795 0 0.00 0 5540 15 0 0
v_min_non_paid_realizn 7175 7175 0 0.00 0 12498 18 0 0
v_our_firm 10206 10206 0 0.00 0 17 1 0 0
v_reserve_write_off 20284 20284 0 0.00 0 8899 8 0 0
x_lock_depdocs_on_canc_invoice 12550 12550 0 0.00 0 25529 120 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 3216 329 0 0
x_make_qstorno_1000_1200 5398 5398 0 0.00 2 34652 1244 0 0
x_make_qstorno_1200_2000 5447 5447 0 0.00 2 80312 3309 0 0
x_make_qstorno_2100_3300 465785 465785 0 0.00 0 105361 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-08-2021 16:39:13 to 29-08-2021 16:39:17.

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:52 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:53 26179 8192000000 10353922192 10672451584 1403870640 14.629 100 86 172 0 1437089984 5292512 27926832
14:10:25 19131 8192000000 10427242656 10756497408 1484673952 15.342 100 90 180 0 1521971168 7865584 29431632
14:11:49 10136 8192000000 10458224528 10797764608 1520231280 15.652 100 77 154 2 1554083760 8814640 25037840
14:13:04 17240 8192000000 10570489184 10922287104 1623440416 16.539 100 80 160 0 1657416960 9704896 24271648
14:14:24 8649 8192000000 10651604544 11021893632 1707371072 17.247 100 80 160 0 1742260240 12028544 22860624
14:15:38 5571 8192000000 10689211984 11067633664 1756331488 17.654 100 74 148 2 1790509568 13653328 20524752
14:16:45 6933 8192000000 10726429632 11106852864 1790017168 17.932 100 78 156 0 1825901168 14351856 21532144
14:17:54 2873 8192000000 10781611728 11165769728 1844384096 18.376 100 90 181 2 1883548496 13291136 25873264
14:18:59 2198 8192000000 10809429616 11198701568 1869546928 18.581 100 90 180 1 1908561632 14003344 25011360
14:20:01 3152 8192000000 10818637680 11209670656 1878734192 18.655 100 75 149 1 1909438896 8004464 22700240
14:21:05 4631 8192000000 10799011728 11219345408 1881751248 18.679 100 29 56 1 1896124816 6844912 7528656
14:22:12 14518 8192000000 10795514672 11230535680 1887489936 18.726 100 12 24 1 1896582864 5809296 3283632
14:23:26 61168 8192000000 10804016160 11243098112 1897155680 18.803 100 20 41 2 1907670896 4943200 5572016
14:25:35 3092 8192000000 10823759776 11257565184 1907685456 18.888 100 32 63 1 1922014112 6352896 7975760
14:26:38 11039 8192000000 10811323376 11245285376 1897805136 18.809 100 11 22 2 1906448944 5432784 3211024
14:27:51 58942 8192000000 10803194864 11236823040 1873535648 18.613 99 42 86 1 1892379888 6096656 12747584
14:29:52 6755 8192000000 10730890784 11144265728 1771597888 17.780 96 23 43 2 1783536240 5865888 6072464
14:31:01 10172 8192000000 10680687296 11078328320 1669763984 16.931 93 19 38 3 1679983984 5069328 5150672
14:32:14 47821 8192000000 10721341120 11139760128 1785781280 17.897 99 22 44 0 1797390960 5097632 6512048
14:34:02 5122 8192000000 10739755248 11166834688 1829400400 18.254 100 32 63 2 1844793296 6825728 8567168
14:35:08 5403 8192000000 10765733152 11181015040 1839402320 18.336 100 36 72 2 1856080256 6368960 10308976
14:36:15 12436 8192000000 10772060256 11205697536 1857577488 18.484 100 16 32 1 1867357008 5391440 4388080
14:37:28 74872 8192000000 10821492544 11246641152 1891046656 18.754 100 35 70 1 1906848880 5906480 9895744
14:39:48 5490 8192000000 10839003424 11267346432 1915237232 18.949 100 40 80 1 1935133088 8512832 11383024
14:40:56 17154 8192000000 10867588416 11299893248 1937045040 19.123 100 48 97 1 1963524080 13009904 13469136
14:42:22 8379 8192000000 10912946768 11325337600 1959667040 19.303 100 75 149 0 1993886448 13331008 20888400
14:43:38 13180 8192000000 10891051632 11322204160 1946017600 19.195 99 39 74 1 1965455488 9113392 10324496
14:45:00 5307 8192000000 10911907040 11339333632 1974773360 19.423 100 51 100 2 1999039264 9587296 14678608
14:46:08 5646 8192000000 10925959168 11339919360 1975209888 19.427 100 68 134 0 2008218656 13524064 19484704
14:47:18 3244 8192000000 10928171504 11357528064 1983794496 19.495 100 61 120 0 2017428352 16146240 17487616
14:48:24 8356 8192000000 10899515520 11327463424 1948595728 19.215 99 57 113 2 1982000800 16597296 16807776
14:49:36 6073 8192000000 10932874848 11355103232 1980805840 19.471 100 81 161 3 2028047984 23031152 24210992
14:50:49 6768 8192000000 10903383696 11327123456 1945606976 19.191 100 60 120 0 1989175632 25759536 17809120
14:52:08 10199 8192000000 10899176768 11330134016 1931466368 19.079 99 46 92 0 1976992384 31242880 14283136
14:53:26 2724 8192000000 10941479056 11345240064 1964145632 19.339 100 86 170 1 2022499504 32746016 25607856
14:54:36 6443 8192000000 10934724448 11344240640 1956475136 19.278 100 84 169 2 2016390976 34285472 25630368
14:55:50 6808 8192000000 10909570912 11332067328 1943692064 19.176 100 55 111 0 1991272368 31468752 16111552
14:57:00 10550 8192000000 10873587872 11298283520 1915985200 18.955 100 46 91 1 1962325504 32502960 13837344
14:58:17 4244 8192000000 10937537104 11353026560 1954830112 19.265 100 77 154 2 2021407520 42777296 23800112
14:59:31 7781 8192000000 10896508304 11327193088 1923336736 19.014 99 53 106 4 1973867440 34156512 16374192
15:00:49 7727 8192000000 10927912144 11362816000 1939513952 19.143 99 52 104 0 2001847936 44743360 17590624
15:02:06 17928 8192000000 10935244560 11384455168 1947385424 19.206 99 34 68 1 2011634480 52207952 12041104
15:03:35 8255 8192000000 10945829104 11382251520 1960666240 19.311 100 42 83 1 2024531776 49392048 14473488
15:04:45 24546 8192000000 10916316048 11366424576 1940898320 19.154 99 25 50 0 1999418704 49806720 8713664
15:06:14 36843 8192000000 10893920512 11350433792 1932185888 19.084 100 21 42 3 1987279040 47709248 7383904
15:08:07 34179 8192000000 10908682976 11350839296 1918079008 18.971 99 25 51 1 1974264400 47756592 8428800
15:09:46 19610 8192000000 10890219760 11360112640 1928934160 19.058 99 15 30 0 1964428176 30832800 4661216
15:11:10 58194 8192000000 10890243968 11361308672 1930007056 19.067 99 11 22 0 1952644240 19461392 3175792
15:13:14 117209 8192000000 10918250560 11373322240 1953673376 19.256 99 17 33 3 1972078224 13277360 5127488
15:16:25 74074 8192000000 10910444512 11376685056 1979558000 19.461 100 12 24 2 2001281744 17998624 3725120
15:18:40 77812 8192000000 10900875680 11364020224 1955895744 19.273 99 18 36 1 1977312896 16374576 5042576
15:21:05 85574 8192000000 10910046864 11369209856 1963405024 19.333 99 21 42 2 1977350752 8189744 5755984
15:23:38 34814 8192000000 10931675952 11378081792 1982389984 19.484 100 34 68 1 2008319520 15745888 10183648
15:25:13 76710 8192000000 10941940416 11399135232 1957439360 19.286 98 38 76 1 1980019584 11470352 11109872
15:27:34 24244 8192000000 10945935664 11411034112 1985137808 19.505 99 20 40 1 2009304976 18021392 6145776
15:29:15 22927 8192000000 10938692304 11396657152 1986652384 19.517 100 54 107 2 2022557744 19455712 16449648
15:30:46 98240 8192000000 10904439808 11378855936 1926677808 19.040 97 16 32 1 1962671520 30322784 5670928
15:33:25 122421 8192000000 10886276656 11359121408 1873532208 18.613 95 14 28 1 1888428560 11078720 3817632
15:36:31 64229 8192000000 10891662944 11373932544 1943311744 19.173 99 12 24 1 1962835728 16926368 2597616
15:38:36 71230 8192000000 10890630720 11374362624 1946862176 19.201 99 19 36 1 1971060432 18675552 5522704
15:40:48 66975 8192000000 10871539520 11349041152 1889131152 18.739 96 27 54 3 1905952144 8562800 8258192
15:42:55 60286 8192000000 10881160832 11351941120 1863377312 18.531 95 15 30 2 1877333520 9482528 4473680
15:44:55 23755 8192000000 10891658848 11359756288 1929615328 19.064 98 39 78 3 1956000656 15592544 10792784
15:46:20 54778 8192000000 10888907392 11364208640 1944923008 19.186 100 15 30 1 1972464704 22999488 4542208
15:48:18 59303 8192000000 10895232624 11360342016 1940314768 19.149 99 11 22 2 1957044704 13745568 2984368
15:50:17 76731 8192000000 10912630480 11377700864 1938183296 19.132 98 19 38 0 1958252272 14832240 5236736
15:52:37 87950 8192000000 10898609584 11374485504 1903164752 18.852 97 16 32 0 1922745216 15331680 4248784
15:55:06 45556 8192000000 10841009536 11301003264 1856115600 18.472 96 27 54 2 1871530752 7223472 8191680
15:57:11 83619 8192000000 10872245952 11349327872 1934498880 19.103 100 32 64 3 1958404192 14937200 8968112
15:59:37 59561 8192000000 10861457872 11346403328 1932848720 19.090 99 30 60 2 1955245328 13625904 8770704
16:01:36 32141 8192000000 10825018800 11300917248 1822724448 18.200 94 11 22 2 1832517024 6294560 3498016
16:03:22 84199 8192000000 10877549824 11358105600 1947152496 19.204 100 13 26 1 1964530464 13148016 4229952
16:05:47 34969 8192000000 10880606432 11369046016 1956919360 19.282 100 18 36 2 1975403936 13815968 4668608
16:07:24 66601 8192000000 10872633008 11354746880 1920978784 18.995 98 10 20 0 1931242480 7757152 2506544
16:09:43 76917 8192000000 10896923536 11364859904 1945006736 19.187 98 17 34 2 1961283968 11899952 4377280
16:12:08 62158 8192000000 10918276016 11400245248 1967503760 19.366 99 18 35 3 1986977888 14237056 5237072
16:14:11 16497 8192000000 10928899424 11393474560 1968963872 19.377 99 41 83 2 1995683072 13799824 12919376
16:15:30 39165 8192000000 10938916240 11387043840 1981137424 19.474 100 42 82 4 2004666896 11758112 11771360
16:17:10 47242 8192000000 10916427712 11396907008 1941917312 19.162 98 23 46 0 1966517664 18239392 6360960
16:19:07 67775 8192000000 10913862768 11398123520 1973345344 19.412 100 11 22 0 1990275152 13768128 3161680
16:21:16 73149 8192000000 10895464096 11367628800 1921168336 18.996 97 20 40 0 1937947536 10857504 5921696
16:23:33 28579 8192000000 10890691136 11354173440 1927233136 19.045 98 39 78 3 1947929472 9741872 10954464
16:25:05 80351 8192000000 10871916272 11361591296 1925515600 19.031 98 11 22 0 1944318480 15996864 2806016
16:28:04 96940 8192000000 10871610320 11356094464 1888638736 18.735 96 9 18 0 1898797264 7559024 2599504
16:30:41 76068 8192000000 10900139376 11388710912 1965271968 19.348 100 12 24 0 1979126288 10723696 3130624
16:32:57 84451 8192000000 10904478224 11375206400 1922259376 19.005 97 17 32 1 1938058880 11186352 4613152
16:35:23 48001 8192000000 10887801872 11368472576 1933893376 19.098 98 18 36 0 1949508800 10499744 5115680
16:37:14 43796 8192000000 9108333776 9341214720 276544864 3.265 14 7 10 2 285221136 6377056 2299216

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-08-2021 16:39:19 to 29-08-2021 16:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 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 29-08-2021 16:39:35 to 29-08-2021 16:39:37.

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          240810
MON$OLDEST_ACTIVE               240811
MON$OLDEST_SNAPSHOT             240811
MON$NEXT_TRANSACTION            240811
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                       2056096
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
--- end of diff output ---

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

29.08.21 16:39:38. 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-08-29 16:39:41 No severe PSQL-related problems occured
29.08.21 16:39:42. End of report.