Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.09.2021 19: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 16295948 kB
MemAvailable 30820048 kB
Buffers 0 kB
Cached 15420236 kB
SwapCached 668 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-21 19:37:49
DTS_END                         2021-09-21 19:37:49.9620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50658
TRN_ID                          222283
ATT_ID                          599
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: 8895.15 23446 1067418
customer order: creation 158.62 431 19034
customer order: refuse 12.68 385 1522
order to supplier: creation 42.50 1464 5100
order to supplier: removal 3.37 108432 404
invoice (draft): creation 41.46 3533 4975
invoice (draft): removal 54.12 822 6494
invoice accept: apply 37.64 19942 4517
invoice accept: cancel 48.02 759 5762
customer reserve: creation 3537.28 193 424473
customer reserve: removal 4684.49 61 562139
realization accept: apply 75.60 223 9072
realization accept: cancel 82.08 116 9850
payment from customer: creation 53.61 137 6433
payment from customer: removal 5.14 108 617
payment to supplier: creation 44.63 158 5355
payment to supplier: removal 2.92 71 350
service: total inventory turnovers 4.29 1203 515
service: total monetary turnovers 5.95 110 714
service: aggregate perf. data 0.73 21564 88
service: refresh index statistics 0.03 309203 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-09-2021 19:40:05 to 21-09-2021 19:40:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2711
TEST_TIME 2 2724
TEST_TIME 3 4470
TEST_TIME 4 4743
TEST_TIME 5 4817
TEST_TIME 6 4341
TEST_TIME 7 4527
TEST_TIME 8 4468
TEST_TIME 9 4627
TEST_TIME 10 4900
TEST_TIME 11 5116
TEST_TIME 12 5182
TEST_TIME 13 5729
TEST_TIME 14 5944
TEST_TIME 15 6394
TEST_TIME 16 6667
TEST_TIME 17 6790
TEST_TIME 18 7914
TEST_TIME 19 8036
TEST_TIME 20 8480
TEST_TIME 21 8441
TEST_TIME 22 9062
TEST_TIME 23 9109
TEST_TIME 24 9150
TEST_TIME 25 9672
TEST_TIME 26 10421
TEST_TIME 27 10178
TEST_TIME 28 9891
TEST_TIME 29 10193
TEST_TIME 30 10266
TEST_TIME 31 10008
TEST_TIME 32 9890
TEST_TIME 33 9651
TEST_TIME 34 9753
TEST_TIME 35 9690
TEST_TIME 36 9554
TEST_TIME 37 9422
TEST_TIME 38 9330
TEST_TIME 39 9228
TEST_TIME 40 9425
TEST_TIME 41 9312
TEST_TIME 42 9194
TEST_TIME 43 9063
TEST_TIME 44 8995
TEST_TIME 45 9004
TEST_TIME 46 8942
TEST_TIME 47 8873
TEST_TIME 48 8794
TEST_TIME 49 8738
TEST_TIME 50 8653
TEST_TIME 51 8750
TEST_TIME 52 8734
TEST_TIME 53 8712
TEST_TIME 54 8807
TEST_TIME 55 8802
TEST_TIME 56 8902
TEST_TIME 57 8931
TEST_TIME 58 8964
TEST_TIME 59 9031
TEST_TIME 60 9073
TEST_TIME 61 8993
TEST_TIME 62 9172
TEST_TIME 63 9117
TEST_TIME 64 9293
TEST_TIME 65 9259
TEST_TIME 66 9165
TEST_TIME 67 9103
TEST_TIME 68 9088
TEST_TIME 69 9028
TEST_TIME 70 9069
TEST_TIME 71 9064
TEST_TIME 72 8995
TEST_TIME 73 8950
TEST_TIME 74 8861
TEST_TIME 75 8917
TEST_TIME 76 8991
TEST_TIME 77 8982
TEST_TIME 78 8974
TEST_TIME 79 9023
TEST_TIME 80 9031
TEST_TIME 81 8965
TEST_TIME 82 9028
TEST_TIME 83 9101
TEST_TIME 84 9080
TEST_TIME 85 9111
TEST_TIME 86 9119
TEST_TIME 87 9132
TEST_TIME 88 9138
TEST_TIME 89 9072
TEST_TIME 90 9004
TEST_TIME 91 8993
TEST_TIME 92 8994
TEST_TIME 93 8938
TEST_TIME 94 8872
TEST_TIME 95 8884
TEST_TIME 96 8891
TEST_TIME 97 8929
TEST_TIME 98 8917
TEST_TIME 99 8955
TEST_TIME 100 8979
TEST_TIME 101 8969
TEST_TIME 102 9003
TEST_TIME 103 9013
TEST_TIME 104 9013
TEST_TIME 105 9112
TEST_TIME 106 9106
TEST_TIME 107 9096
TEST_TIME 108 9111
TEST_TIME 109 9133
TEST_TIME 110 9153
TEST_TIME 111 9100
TEST_TIME 112 9055
TEST_TIME 113 9058
TEST_TIME 114 9024
TEST_TIME 115 9041
TEST_TIME 116 8998
TEST_TIME 117 8991
TEST_TIME 118 8956
TEST_TIME 119 8919
TEST_TIME 120 8904

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-09-2021 19:40:08 to 21-09-2021 19:40:11.

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 1461410 1461410 0 0.00 0 59509 6 0 0
doc_list_biud 1461403 1461403 0 0.00 0 35451 30 0 0
sp_add_invoice_to_stock 4617 4517 100 2.16 43 197093 19942 0 100
sp_cancel_adding_invoice 5762 5762 0 0.00 5 61022 760 0 0
sp_cancel_client_order 1522 1522 0 0.00 5 20192 385 0 0
sp_cancel_customer_reserve 562139 562139 0 0.00 1 33663 61 0 0
sp_cancel_pay_from_customer 617 617 0 0.00 3 5943 109 0 0
sp_cancel_pay_to_supplier 350 350 0 0.00 3 1540 71 0 0
sp_cancel_supplier_invoice 6501 6494 7 0.10 2 35459 822 0 7
sp_cancel_supplier_order 404 404 0 0.00 5 1038292 108432 0 0
sp_cancel_write_off 9850 9850 0 0.00 1 30676 117 0 0
sp_client_order 19034 19034 0 0.00 4 29693 432 0 0
sp_customer_reserve 424473 424473 0 0.00 0 71447 194 0 0
sp_fill_shopping_cart 29101 29101 0 0.00 1 15495 41 0 0
sp_fill_shopping_cart_clo_res 424453 424453 0 0.00 0 12082 15 0 0
sp_get_clo_for_invoice 4496 4496 0 0.00 0 22008 357 0 0
sp_kill_cost_storno 16579 16579 0 0.00 0 8752 59 0 0
sp_kill_qstorno_ret_qs2qd 1141026 1141026 0 0.00 0 35448 33 0 0
sp_kill_qty_storno 599738 599738 0 0.00 0 35450 72 0 0
sp_lock_selected_doc 18824 18824 0 0.00 0 8489 6 0 0
sp_make_cost_storno 25356 25356 0 0.00 0 59507 61 0 0
sp_multiply_rows_for_pdistr 25356 25356 0 0.00 0 8421 29 0 0
sp_multiply_rows_for_qdistr 19034 19034 0 0.00 0 10103 177 0 0
sp_pay_from_customer 6433 6433 0 0.00 4 71944 137 0 0
sp_pay_to_supplier 5355 5355 0 0.00 5 59515 158 0 0
sp_payment_common 11788 11788 0 0.00 1 71934 36 0 0
sp_qd_handle_on_cancel_clo 1522 1522 0 0.00 0 20181 342 0 0
sp_qd_handle_on_invoice_upd_sts 10257 10257 0 0.00 0 34456 342 0 0
sp_qd_handle_on_reserve_upd_sts 9072 9072 0 0.00 0 24887 91 0 0
sp_reserve_write_off 9072 9072 0 0.00 7 25123 224 0 0
sp_supplier_invoice 4975 4975 0 0.00 6 90673 3534 0 0
sp_supplier_order 5100 5100 0 0.00 5 72713 1464 0 0
srv_aggregate_perf_data 88 88 0 0.00 2663 324517 21564 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2033 119792 46672 0 0
srv_find_qd_qs_mism 1024206 1024206 0 0.00 0 13486 5 0 0
srv_make_invnt_saldo 515 515 0 0.00 36 85220 1204 0 0
srv_make_money_saldo 714 714 0 0.00 1 57426 111 0 0
srv_recalc_idx_stat 4 4 0 0.00 168387 429188 309203 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4518 4518 0 0.00 0 30366 197 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 0 2531 63 0 0
t$perf-norm:sp_cancel_client_order 1522 1522 0 0.00 0 10394 20 0 0
t$perf-norm:sp_cancel_customer_reserve 646 646 0 0.00 0 5588 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 617 617 0 0.00 0 1791 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 350 350 0 0.00 0 1619 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 6915 47 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 31982 1710 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 458 3 0 0
t$perf-norm:sp_client_order 19034 19034 0 0.00 0 10771 19 0 0
t$perf-norm:sp_customer_reserve 937 937 0 0.00 0 1116 3 0 0
t$perf-norm:sp_pay_from_customer 6433 6433 0 0.00 0 10877 13 0 0
t$perf-norm:sp_pay_to_supplier 5355 5355 0 0.00 0 8111 15 0 0
t$perf-norm:sp_reserve_write_off 9072 9072 0 0.00 0 27524 20 0 0
t$perf-norm:sp_supplier_invoice 4975 4975 0 0.00 0 6363 18 0 0
t$perf-norm:sp_supplier_order 5100 5100 0 0.00 0 9182 17 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 28 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 131 6 0 0
t$perf-norm:srv_make_invnt_saldo 515 515 0 0.00 0 2234 23 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 1002 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9186 8986 200 2.17 0 11691 25 0 200
v_all_customers 34802 34802 0 0.00 0 57 1 0 0
v_all_suppliers 28052 28052 0 0.00 0 21 1 0 0
v_all_wares 38068 38068 0 0.00 0 82 1 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 6060 22 0 0
v_cancel_client_order 3044 3044 0 0.00 0 9639 14 0 0
v_cancel_customer_prepayment 1234 1234 0 0.00 0 5206 14 0 0
v_cancel_customer_reserve 1292 1292 0 0.00 0 4225 9 0 0
v_cancel_payment_to_supplier 700 700 0 0.00 0 1054 6 0 0
v_cancel_supplier_invoice 798 784 14 1.75 0 1547 17 0 14
v_cancel_supplier_order 808 808 0 0.00 0 6065 11 0 0
v_cancel_write_off 1918 1918 0 0.00 0 6504 19 0 0
v_max_id_clo_ord 5100 5100 0 0.00 0 3677 2 0 0
v_max_id_clo_res 937 937 0 0.00 0 5023 26 0 0
v_max_id_ord_sup 4967 4967 0 0.00 0 3066 4 0 0
v_max_non_paid_invoice 1396 1396 0 0.00 0 107 2 0 0
v_max_non_paid_realizn 3369 3369 0 0.00 0 2624 5 0 0
v_min_id_clo_ord 5100 5100 0 0.00 0 7478 10 0 0
v_min_id_clo_res 937 937 0 0.00 0 7808 22 0 0
v_min_id_ord_sup 4967 4967 0 0.00 0 5966 9 0 0
v_min_non_paid_invoice 5355 5355 0 0.00 0 6739 16 0 0
v_min_non_paid_realizn 6433 6433 0 0.00 0 71931 29 0 0
v_our_firm 9394 9394 0 0.00 0 14 1 0 0
v_reserve_write_off 18144 18144 0 0.00 0 8418 10 0 0
x_lock_depdocs_on_canc_invoice 11864 11864 0 0.00 0 22147 116 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 6393 350 0 0
x_make_qstorno_1000_1200 5100 5100 0 0.00 2 72635 1383 0 0
x_make_qstorno_1200_2000 4975 4975 0 0.00 3 90657 3383 0 0
x_make_qstorno_2100_3300 424473 424473 0 0.00 0 71446 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-09-2021 19:40:12 to 21-09-2021 19:40:16.

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:53 3 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:54 28037 8192000000 10381587056 10703028224 1434548160 14.901 100 88 175 0 1469162544 6098768 28515616
17:10:28 26155 8192000000 10456760448 10790760448 1510224704 15.565 100 88 176 0 1546810320 8700896 27884720
17:12:03 20466 8192000000 10501167152 10844520448 1554845040 15.952 100 86 172 0 1590939104 9729424 26364640
17:13:28 8232 8192000000 10572330640 10930434048 1634200416 16.631 100 78 156 0 1668401520 10451888 23749216
17:14:42 7554 8192000000 10651787712 11017957376 1705782944 17.233 100 82 164 0 1744548608 15987120 22778544
17:15:56 7000 8192000000 10706832064 11083853824 1763521872 17.714 100 76 153 0 1798380208 13389040 21469296
17:17:06 3541 8192000000 10752608752 11136884736 1813400784 18.124 100 77 153 2 1846849792 11975296 21473712
17:18:12 2083 8192000000 10798881072 11184455680 1851794608 18.437 100 90 180 2 1891861648 15052608 25014432
17:19:16 1496 8192000000 10823705904 11210342400 1882066080 18.682 100 94 189 2 1921524704 12316256 27142368
17:20:18 5261 8192000000 10790894864 11207966720 1879150624 18.658 100 45 89 1 1900830624 9441984 12238016
17:21:26 57246 8192000000 10799302464 11236495360 1888169424 18.731 100 43 83 1 1906708800 6380976 12158400
17:23:27 4595 8192000000 10820344352 11243757568 1899298432 18.821 100 36 68 0 1915821504 7052208 9470864
17:24:33 9308 8192000000 10817513856 11250552832 1906471216 18.878 100 20 37 1 1917283168 5591184 5220768
17:25:43 8421 8192000000 10830373984 11261005824 1911681024 18.920 100 28 55 1 1925162112 5136032 8345056
17:26:52 13440 8192000000 10835745392 11265245184 1915771408 18.953 100 40 81 1 1933571136 5986736 11812992
17:28:11 5618 8192000000 10811362496 11245383680 1902904672 18.850 100 36 72 2 1920213344 6931792 10376880
17:29:18 11417 8192000000 10759574288 11182518272 1831780528 18.274 98 24 47 0 1845235104 5400240 8054336
17:30:30 8455 8192000000 10720483168 11141099520 1760005024 17.684 96 19 38 0 1770961712 5153232 5803456
17:31:41 25098 8192000000 10709550144 11125637120 1734209504 17.471 95 43 85 0 1754232736 7114000 12909232
17:33:10 5812 8192000000 10710257168 11126808576 1769623264 17.764 98 25 49 1 1782320576 5527680 7169632
17:34:16 10551 8192000000 10742931792 11169955840 1834712512 18.298 100 33 64 3 1850599168 5414624 10472032
17:35:30 12201 8192000000 10761500688 11196932096 1857465648 18.483 100 17 34 0 1867794960 5626960 4702352
17:36:44 47968 8192000000 10797979680 11229134848 1877950304 18.649 100 17 32 0 1886857984 4231088 4676592
17:38:42 29847 8192000000 10856529680 11272179712 1912653840 18.928 100 50 100 0 1937177824 10473520 14050464
17:40:19 12609 8192000000 10869844704 11288743936 1921806592 19.001 99 59 118 0 1958001904 18940544 17254768
17:41:42 10847 8192000000 10904306208 11331153920 1955368592 19.269 100 41 80 3 1984278224 17105120 11804512
17:42:59 17177 8192000000 10915674880 11346550784 1976433264 19.436 100 44 88 0 2007672448 17908016 13331168
17:44:42 25540 8192000000 10926071712 11370233856 1988470848 19.532 100 46 92 1 2031820656 29184528 14165280
17:46:27 5729 8192000000 10962067184 11389575168 2006617840 19.675 100 61 121 0 2051739712 26669808 18452064
17:47:36 4654 8192000000 10958937584 11384840192 1995805088 19.590 99 67 134 2 2045830256 29110016 20915152
17:48:48 9076 8192000000 10975853584 11406983168 2016555680 19.753 100 55 110 1 2062775664 29831232 16388752
17:50:05 3136 8192000000 10995638384 11424239616 2026969952 19.835 100 74 149 0 2085647328 35923424 22753952
17:51:12 6017 8192000000 11025219280 11450949632 2043356912 19.963 100 76 152 0 2108765184 41412144 23996128
17:52:27 2030 8192000000 11017325200 11434262528 2025175568 19.821 100 84 166 1 2095558208 43703200 26679440
17:53:35 2106 8192000000 11001833424 11415814144 2000485696 19.627 100 89 179 4 2080799728 51939744 28374288
17:54:40 2942 8192000000 11028594480 11444797440 2022821856 19.802 100 90 181 2 2107880672 56008240 29050576
17:55:52 3029 8192000000 10968967888 11400613888 2000648864 19.628 100 79 160 0 2060699040 34905216 25144960
17:57:00 10342 8192000000 10953187616 11400126464 1991200256 19.553 100 43 86 0 2043922496 38470304 14251936
17:58:57 17031 8192000000 10951855024 11406610432 1985613168 19.509 100 36 72 2 2039988848 42301456 12074224
18:00:31 15708 8192000000 10934871008 11397120000 1983217504 19.490 100 26 52 0 2023671040 31847504 8606032
18:01:57 27123 8192000000 10922646896 11378216960 1938632208 19.136 99 27 54 1 1999930480 51203952 10094320
18:03:28 38027 8192000000 10893820144 11355000832 1921540048 18.999 98 13 26 0 1957950928 32195200 4215680
18:05:06 17845 8192000000 10902477536 11346984960 1952714480 19.248 100 51 99 2 1985703376 16833552 16155344
18:06:25 41593 8192000000 10836982320 11308343296 1895034032 18.786 96 47 94 1 1929738720 20617920 14086768
18:08:07 40339 8192000000 10871343872 11349299200 1938791280 19.137 99 10 20 0 1951623760 10258992 2573488
18:09:49 64622 8192000000 10859805328 11316375552 1865622144 18.549 95 37 72 0 1887520624 11315888 10582592
18:12:00 26854 8192000000 10879404384 11333472256 1946819072 19.201 100 27 54 2 1965844160 11535488 7489600
18:13:33 119791 8192000000 10895419152 11342815232 1913431616 18.934 98 31 62 1 1935286592 13869360 7985616
18:16:34 67661 8192000000 10885392400 11371233280 1966685504 19.359 100 25 50 0 1991650496 17733056 7231936
18:18:46 76232 8192000000 10867425696 11333136384 1898352336 18.813 97 16 32 0 1909640032 7207744 4079952
18:21:03 95935 8192000000 10848250368 11306119168 1885692960 18.711 97 22 44 0 1903676656 11551552 6432144
18:23:46 9591 8192000000 10854290320 11320143872 1940306768 19.149 100 23 44 1 1952768160 6550448 5910944
18:25:25 40093 8192000000 10854191024 11322875904 1941187136 19.156 100 20 40 1 1956134784 9641520 5306128
18:29:38 27991 8192000000 10903082400 11363803136 1971161504 19.395 100 36 71 2 1989913344 7409936 11341904
18:31:12 42180 8192000000 10870892096 11341524992 1925739168 19.033 98 17 34 2 1940106880 9511344 4856368
18:32:55 48672 8192000000 10883108704 11361468416 1964527792 19.342 100 39 78 1 1990069008 14816656 10724560
18:34:50 57360 8192000000 10880126688 11349200896 1927293232 19.045 98 17 34 0 1944175936 11517696 5365008
18:37:05 63288 8192000000 10890849600 11345723392 1932487776 19.087 98 18 34 0 1948768720 12199408 4081536
18:39:11 60416 8192000000 10873241760 11341398016 1878976912 18.657 96 21 42 0 1900471952 16042384 5452656
18:41:20 66215 8192000000 10896350928 11387953152 1957192864 19.284 99 21 42 0 1987029952 23826368 6010720
18:43:27 77533 8192000000 10893621456 11357966336 1941469600 19.158 98 33 66 0 1957429280 6150336 9809344
18:45:45 54185 8192000000 10907607664 11371991040 1964380576 19.341 99 40 80 1 1987176448 11528880 11266992
18:47:44 79454 8192000000 10873091280 11349106688 1881016640 18.673 95 13 26 0 1895037808 10408896 3612272
18:50:08 63737 8192000000 10879979840 11355037696 1961093168 19.315 100 15 30 0 1979801248 13917488 4790592
18:52:15 54604 8192000000 10870795408 11348885504 1896899344 18.801 97 20 40 1 1920291760 16729680 6662736
18:54:17 81482 8192000000 10891413616 11351490560 1919901152 18.986 98 19 38 2 1938159664 13133424 5125088
18:56:39 79109 8192000000 10852046480 11330572288 1842103504 18.358 94 23 46 0 1859596096 10814480 6678112
18:58:59 29421 8192000000 10902977664 11345014784 1930661776 19.072 99 49 98 0 1961992976 17327088 14004112
19:00:34 96271 8192000000 10874673024 11341701120 1929874976 19.066 99 15 30 0 1947815920 13696128 4244816
19:03:17 71439 8192000000 10871984768 11336077312 1896906112 18.801 97 31 62 0 1915610256 9859504 8844640
19:05:35 104813 8192000000 10866616272 11334590464 1913598208 18.936 98 19 38 1 1930466288 11433312 5434768
19:08:21 61299 8192000000 10884469312 11356606464 1962965936 19.330 100 13 26 0 1976050176 9115248 3968992
19:10:23 48199 8192000000 10868844960 11347316736 1927059104 19.043 98 8 16 0 1939032224 9867472 2105648
19:12:12 57236 8192000000 10903560352 11378032640 1956845760 19.281 99 13 26 1 1973112560 12900272 3366528
19:14:27 59232 8192000000 10890870096 11357814784 1931084288 19.076 98 22 42 0 1950751712 13233856 6433568
19:16:32 97573 8192000000 10898108000 11377975296 1942770896 19.169 98 6 12 0 1956338352 12135520 1431936
19:19:30 53142 8192000000 10929786352 11412541440 1989422096 19.539 100 17 34 0 2014265040 20277152 4565792
19:21:30 45428 8192000000 10934348880 11400884224 1969088272 19.378 99 17 34 0 1994844288 20756208 4999808
19:23:16 116257 8192000000 10917478896 11367329792 1894653712 18.783 95 12 24 0 1913281184 14764752 3862720
19:27:23 74009 8192000000 10903033904 11376619520 1919976992 18.987 97 28 56 0 1945008480 17278400 7753088
19:30:22 57185 8192000000 10900479856 11368484864 1915657440 18.952 97 13 25 1 1933153056 13866080 3629536
19:32:35 67316 8192000000 10878889632 11366363136 1950175296 19.228 99 11 22 0 1963034800 9890816 2968688
19:34:50 68463 8192000000 10905140848 11378872320 1961988448 19.322 99 14 28 0 1981202704 15330352 3883904
19:37:28 39881 8192000000 9418335984 9723899904 543218880 6.218 28 12 16 3 555866832 9202816 3445136

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-09-2021 19:40:18 to 21-09-2021 19:40:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-09-2021 19:40:34 to 21-09-2021 19:40:36.

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          218078
MON$OLDEST_ACTIVE               222565
MON$OLDEST_SNAPSHOT             222565
MON$NEXT_TRANSACTION            222565
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                       2010240
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.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Sep 21 19:38:10 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Sep 21 19:38:27 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
--- end of diff output ---

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

21.09.21 19:40:37. 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-21 19:40:39 No severe PSQL-related problems occured
21.09.21 19:40:40. End of report.