Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.08.2021 22: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 16076036 kB
MemAvailable 31028292 kB
Buffers 0 kB
Cached 15551880 kB
SwapCached 2560 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-02 22:37:42
DTS_END                         2021-08-02 22:37:42.1900
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/55830
TRN_ID                          251794
ATT_ID                          736
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: 9619.08 16902 1154289
customer order: creation 186.23 353 22348
customer order: refuse 16.11 333 1933
order to supplier: creation 49.01 1334 5881
order to supplier: removal 3.66 101394 439
invoice (draft): creation 48.53 3251 5824
invoice (draft): removal 54.49 764 6539
invoice accept: apply 45.00 17563 5400
invoice accept: cancel 48.96 833 5875
customer reserve: creation 4117.33 175 494080
customer reserve: removal 4739.83 63 568779
realization accept: apply 90.78 179 10894
realization accept: cancel 80.54 110 9665
payment from customer: creation 64.00 125 7680
payment from customer: removal 6.33 85 760
payment to supplier: creation 52.03 133 6243
payment to supplier: removal 3.47 95 416
service: total inventory turnovers 5.08 1177 610
service: total monetary turnovers 6.78 52 813
service: aggregate perf. data 0.88 17472 106
service: refresh index statistics 0.03 192555 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-08-2021 22:38:22 to 02-08-2021 22:38:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5831
TEST_TIME 2 7256
TEST_TIME 3 6968
TEST_TIME 4 6061
TEST_TIME 5 5902
TEST_TIME 6 6050
TEST_TIME 7 5936
TEST_TIME 8 6079
TEST_TIME 9 5989
TEST_TIME 10 6053
TEST_TIME 11 5970
TEST_TIME 12 5914
TEST_TIME 13 6213
TEST_TIME 14 6188
TEST_TIME 15 6470
TEST_TIME 16 6839
TEST_TIME 17 7389
TEST_TIME 18 7453
TEST_TIME 19 7576
TEST_TIME 20 7895
TEST_TIME 21 8171
TEST_TIME 22 8284
TEST_TIME 23 8259
TEST_TIME 24 8333
TEST_TIME 25 8219
TEST_TIME 26 8844
TEST_TIME 27 9127
TEST_TIME 28 9282
TEST_TIME 29 9677
TEST_TIME 30 10553
TEST_TIME 31 10737
TEST_TIME 32 10549
TEST_TIME 33 10566
TEST_TIME 34 10697
TEST_TIME 35 10647
TEST_TIME 36 10675
TEST_TIME 37 10589
TEST_TIME 38 10426
TEST_TIME 39 10313
TEST_TIME 40 10142
TEST_TIME 41 10248
TEST_TIME 42 10229
TEST_TIME 43 10090
TEST_TIME 44 10019
TEST_TIME 45 9965
TEST_TIME 46 9870
TEST_TIME 47 9858
TEST_TIME 48 9942
TEST_TIME 49 9905
TEST_TIME 50 9804
TEST_TIME 51 9882
TEST_TIME 52 9921
TEST_TIME 53 10013
TEST_TIME 54 9901
TEST_TIME 55 9986
TEST_TIME 56 10110
TEST_TIME 57 10042
TEST_TIME 58 10016
TEST_TIME 59 10117
TEST_TIME 60 10065
TEST_TIME 61 10121
TEST_TIME 62 10095
TEST_TIME 63 10135
TEST_TIME 64 10209
TEST_TIME 65 10269
TEST_TIME 66 10320
TEST_TIME 67 10211
TEST_TIME 68 10108
TEST_TIME 69 10124
TEST_TIME 70 10113
TEST_TIME 71 10033
TEST_TIME 72 9967
TEST_TIME 73 10027
TEST_TIME 74 9997
TEST_TIME 75 9971
TEST_TIME 76 9946
TEST_TIME 77 9905
TEST_TIME 78 9868
TEST_TIME 79 9827
TEST_TIME 80 9777
TEST_TIME 81 9736
TEST_TIME 82 9661
TEST_TIME 83 9643
TEST_TIME 84 9633
TEST_TIME 85 9637
TEST_TIME 86 9658
TEST_TIME 87 9667
TEST_TIME 88 9672
TEST_TIME 89 9710
TEST_TIME 90 9696
TEST_TIME 91 9713
TEST_TIME 92 9717
TEST_TIME 93 9730
TEST_TIME 94 9743
TEST_TIME 95 9766
TEST_TIME 96 9833
TEST_TIME 97 9809
TEST_TIME 98 9848
TEST_TIME 99 9842
TEST_TIME 100 9767
TEST_TIME 101 9709
TEST_TIME 102 9649
TEST_TIME 103 9678
TEST_TIME 104 9663
TEST_TIME 105 9609
TEST_TIME 106 9567
TEST_TIME 107 9559
TEST_TIME 108 9532
TEST_TIME 109 9564
TEST_TIME 110 9526
TEST_TIME 111 9567
TEST_TIME 112 9619
TEST_TIME 113 9603
TEST_TIME 114 9657
TEST_TIME 115 9640
TEST_TIME 116 9613
TEST_TIME 117 9639
TEST_TIME 118 9615
TEST_TIME 119 9654
TEST_TIME 120 9625

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-08-2021 22:38:26 to 02-08-2021 22:38:29.

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 1613438 1613438 0 0.00 0 22203 6 0 0
doc_list_biud 1613438 1613438 0 0.00 0 62183 28 0 0
sp_add_invoice_to_stock 5528 5400 128 2.31 52 177282 17564 0 128
sp_cancel_adding_invoice 5875 5875 0 0.00 2 97601 833 0 0
sp_cancel_client_order 1933 1933 0 0.00 4 9517 334 0 0
sp_cancel_customer_reserve 568779 568779 0 0.00 1 62186 64 0 0
sp_cancel_pay_from_customer 760 760 0 0.00 2 4805 86 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 6939 96 0 0
sp_cancel_supplier_invoice 6548 6539 9 0.13 1 10487 765 0 9
sp_cancel_supplier_order 439 439 0 0.00 6 1208462 101395 0 0
sp_cancel_write_off 9665 9665 0 0.00 1 8158 111 0 0
sp_client_order 22348 22348 0 0.00 4 83146 354 0 0
sp_customer_reserve 494080 494080 0 0.00 0 86947 176 0 0
sp_fill_shopping_cart 34045 34045 0 0.00 1 13948 36 0 0
sp_fill_shopping_cart_clo_res 494077 494077 0 0.00 0 14797 12 0 0
sp_get_clo_for_invoice 5392 5392 0 0.00 0 16856 263 0 0
sp_kill_cost_storno 16716 16716 0 0.00 0 17946 54 0 0
sp_kill_qstorno_ret_qs2qd 1154201 1154201 0 0.00 0 62180 34 0 0
sp_kill_qty_storno 609516 609516 0 0.00 0 62183 73 0 0
sp_lock_selected_doc 22557 22557 0 0.00 0 9409 7 0 0
sp_make_cost_storno 30209 30209 0 0.00 0 22198 50 0 0
sp_multiply_rows_for_pdistr 30209 30209 0 0.00 0 11611 29 0 0
sp_multiply_rows_for_qdistr 22348 22348 0 0.00 0 21063 159 0 0
sp_pay_from_customer 7680 7680 0 0.00 4 17645 125 0 0
sp_pay_to_supplier 6243 6243 0 0.00 5 21180 134 0 0
sp_payment_common 13923 13923 0 0.00 1 21153 33 0 0
sp_qd_handle_on_cancel_clo 1933 1933 0 0.00 0 6680 300 0 0
sp_qd_handle_on_invoice_upd_sts 11267 11267 0 0.00 0 17878 320 0 0
sp_qd_handle_on_reserve_upd_sts 10894 10894 0 0.00 0 17630 60 0 0
sp_reserve_write_off 10894 10894 0 0.00 6 17638 180 0 0
sp_supplier_invoice 5824 5824 0 0.00 6 82473 3251 0 0
sp_supplier_order 5881 5881 0 0.00 5 39057 1335 0 0
srv_aggregate_perf_data 106 106 0 0.00 3020 221691 17472 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1497 142492 45366 0 0
srv_find_qd_qs_mism 1103931 1103931 0 0.00 0 21364 6 0 0
srv_make_invnt_saldo 610 610 0 0.00 22 95653 1177 0 0
srv_make_money_saldo 813 813 0 0.00 1 20132 52 0 0
srv_recalc_idx_stat 4 4 0 0.00 126646 340487 192555 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 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5400 5400 0 0.00 0 21216 176 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 5982 86 0 0
t$perf-norm:sp_cancel_client_order 1933 1933 0 0.00 0 5090 19 0 0
t$perf-norm:sp_cancel_customer_reserve 753 753 0 0.00 0 5190 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 760 760 0 0.00 0 2624 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 2300 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 676 15 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 26433 1694 0 0
t$perf-norm:sp_cancel_write_off 1097 1097 0 0.00 0 2517 7 0 0
t$perf-norm:sp_client_order 22348 22348 0 0.00 0 6474 16 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 1423 10 0 0
t$perf-norm:sp_pay_from_customer 7680 7680 0 0.00 0 7460 9 0 0
t$perf-norm:sp_pay_to_supplier 6243 6243 0 0.00 0 8694 16 0 0
t$perf-norm:sp_reserve_write_off 10894 10894 0 0.00 0 10559 13 0 0
t$perf-norm:sp_supplier_invoice 5824 5824 0 0.00 0 6075 16 0 0
t$perf-norm:sp_supplier_order 5881 5881 0 0.00 0 10382 19 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 428 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 80 2 0 0
t$perf-norm:srv_make_invnt_saldo 610 610 0 0.00 0 7732 46 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 6335 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 11040 10784 256 2.31 0 9440 22 0 256
v_all_customers 40444 40444 0 0.00 0 47 1 0 0
v_all_suppliers 32198 32198 0 0.00 0 28 1 0 0
v_all_wares 44696 44696 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 4390 16 0 0
v_cancel_client_order 3866 3866 0 0.00 0 3786 7 0 0
v_cancel_customer_prepayment 1520 1520 0 0.00 0 1992 7 0 0
v_cancel_customer_reserve 1506 1506 0 0.00 0 10634 15 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 3174 12 0 0
v_cancel_supplier_invoice 902 884 18 1.99 0 2111 18 0 18
v_cancel_supplier_order 878 878 0 0.00 0 2977 6 0 0
v_cancel_write_off 2194 2194 0 0.00 0 3020 15 0 0
v_max_id_clo_ord 5878 5878 0 0.00 0 2969 2 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 4586 24 0 0
v_max_id_ord_sup 5819 5819 0 0.00 0 3771 4 0 0
v_max_non_paid_invoice 1841 1841 0 0.00 0 3569 4 0 0
v_max_non_paid_realizn 4223 4223 0 0.00 0 3984 5 0 0
v_min_id_clo_ord 5878 5878 0 0.00 0 2062 5 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 8715 15 0 0
v_min_id_ord_sup 5819 5819 0 0.00 0 6378 11 0 0
v_min_non_paid_invoice 6243 6243 0 0.00 0 21149 25 0 0
v_min_non_paid_realizn 7680 7680 0 0.00 0 17627 20 0 0
v_our_firm 11166 11166 0 0.00 0 21 1 0 0
v_reserve_write_off 21788 21788 0 0.00 0 4213 8 0 0
x_lock_depdocs_on_canc_invoice 11972 11972 0 0.00 0 17463 110 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 14136 384 0 0
x_make_qstorno_1000_1200 5881 5881 0 0.00 2 39048 1259 0 0
x_make_qstorno_1200_2000 5824 5824 0 0.00 2 68521 3116 0 0
x_make_qstorno_2100_3300 494080 494080 0 0.00 0 86944 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-08-2021 22:38:30 to 02-08-2021 22:38:34.

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
20:07:45 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:46 31305 8192000000 10355933600 10676043776 1411521808 14.697 100 91 182 1 1447609856 6397328 29690720
20:10:22 16341 8192000000 10430619824 10759413760 1486213856 15.356 100 94 188 0 1525195024 9114384 29866784
20:11:51 16759 8192000000 10474382688 10821443584 1534669792 15.777 100 81 163 0 1569997296 10370128 24957376
20:13:12 12314 8192000000 10574518800 10930831360 1621387680 16.522 100 80 160 0 1657022144 12079648 23554816
20:14:29 9544 8192000000 10628088544 10997493760 1694096464 17.136 100 84 166 1 1730704432 12452864 24155104
20:15:40 4580 8192000000 10697362048 11073044480 1755858064 17.650 100 79 158 0 1791693568 13504880 22330624
20:16:46 3565 8192000000 10741542064 11122192384 1799385216 18.009 100 75 150 1 1832781552 13457104 19939232
20:17:50 1827 8192000000 10790400768 11174649856 1846307920 18.392 100 98 193 2 1886569280 13628608 26632752
20:18:53 27771 8192000000 10817099232 11204698112 1872764624 18.607 100 71 142 1 1903356400 9676240 20915536
20:20:22 6863 8192000000 10785832288 11214163968 1877537520 18.645 100 39 74 3 1895261472 7288720 10435232
20:21:29 8557 8192000000 10791624480 11220279296 1882469552 18.685 100 12 24 0 1891428160 5859008 3099600
20:22:38 64665 8192000000 10803649440 11241435136 1897086576 18.803 100 37 74 2 1913103200 5073760 10942864
20:24:44 3935 8192000000 10824534464 11251081216 1892899376 18.769 99 47 92 1 1914016544 8103984 13013184
20:25:50 9229 8192000000 10810813600 11249913856 1886517936 18.718 99 20 40 5 1898573424 6197504 5857984
20:27:03 62654 8192000000 10774181328 11206426624 1762585376 17.706 93 21 42 1 1774102512 5034000 6483136
20:29:07 5649 8192000000 10775908432 11192061952 1848932832 18.413 100 41 83 2 1867974480 7145200 11896448
20:30:15 6340 8192000000 10732994400 11148587008 1804681472 18.052 99 13 26 2 1813730272 5113008 3935792
20:31:22 55447 8192000000 10703817424 11119075328 1768070096 17.751 97 29 57 0 1780608656 4365968 8172592
20:33:23 10528 8192000000 10766289792 11184783360 1839091056 18.333 100 28 56 2 1853271104 6418272 7761776
20:34:35 7248 8192000000 10776503648 11207770112 1860541344 18.508 100 16 33 2 1870711216 5871648 4298224
20:35:44 6311 8192000000 10792201952 11224547328 1877066064 18.641 100 26 51 3 1890033264 6194160 6773040
20:36:54 42027 8192000000 10814339040 11251240960 1900505232 18.830 100 22 44 1 1911419184 4819904 6094048
20:38:40 9806 8192000000 10871228176 11293274112 1935554816 19.111 100 55 110 1 1960760432 9639920 15565696
20:39:54 8294 8192000000 10897089776 11314991104 1952816208 19.249 100 63 125 0 1980792016 10427168 17548640
20:41:06 32293 8192000000 10913006240 11345051648 1967053120 19.362 100 59 114 3 1999528832 16551696 15924016
20:42:47 9034 8192000000 10913596624 11347009536 1962779648 19.328 99 52 101 0 1990989312 13967056 14242608
20:43:58 10266 8192000000 10951599472 11370962944 1995515136 19.587 100 70 138 1 2032230864 15990064 20725664
20:45:14 14011 8192000000 10940319488 11372285952 1994835392 19.582 100 59 118 1 2031971456 19765552 17370512
20:46:37 19321 8192000000 10948008720 11389468672 1997359440 19.602 100 41 82 2 2032444672 22244736 12840496
20:48:03 8548 8192000000 10969381824 11404177408 2012374480 19.720 100 51 102 1 2052952896 25143040 15435376
20:49:31 7748 8192000000 10979005808 11413053440 2016565664 19.753 100 66 130 2 2066495840 29917168 20013008
20:50:48 9031 8192000000 10976883712 11420336128 2019089616 19.773 100 46 90 2 2068838816 35347264 14401936
20:52:04 1497 8192000000 10973318736 11399561216 2002047904 19.639 100 69 139 1 2059779376 35684544 22046928
20:53:17 7114 8192000000 10949704128 11371290624 1961195184 19.316 99 71 140 1 2024129248 41621488 21312576
20:54:32 4778 8192000000 10955856080 11383492608 1957884096 19.289 98 63 127 0 2016597328 39517328 19195904
20:55:40 3497 8192000000 10959119296 11377942528 1969299984 19.380 100 79 156 1 2039077872 46588928 23188960
20:56:49 5485 8192000000 10967451232 11384422400 1971551552 19.398 100 73 145 1 2042966240 48944720 22469968
20:58:03 2919 8192000000 10966598896 11385884672 1978514448 19.453 100 82 164 0 2050096048 45136112 26445488
20:59:10 6624 8192000000 10952018608 11375112192 1970669584 19.391 100 54 109 1 2031514784 43344448 17500752
21:00:26 8321 8192000000 10947571744 11375853568 1970360128 19.388 100 64 125 0 2036929792 46666320 19903344
21:01:39 13418 8192000000 10962187312 11401187328 1983059824 19.489 100 38 76 1 2044040592 48360768 12620000
21:03:01 9360 8192000000 10969714000 11416162304 1992824544 19.566 100 59 118 3 2071841696 59621728 19395424
21:04:12 12164 8192000000 10940985264 11393126400 1965951600 19.353 100 23 47 2 2022648304 48783136 7913568
21:05:34 28279 8192000000 10924031712 11389702144 1953294032 19.253 100 22 45 1 2014703040 53256112 8152896
21:07:47 50986 8192000000 10884557440 11353059328 1939920384 19.146 99 10 19 0 1963595584 21569040 2106160
21:09:41 50414 8192000000 10913321152 11359907840 1967108016 19.362 100 21 43 1 1989537136 16856432 5572688
21:11:33 38163 8192000000 10901029408 11356823552 1955896928 19.273 99 16 33 2 1970650464 10635792 4117744
21:13:12 32293 8192000000 10911219392 11377504256 1945661872 19.192 98 10 20 0 1966582640 18081872 2838896
21:14:52 40974 8192000000 10918435728 11374735360 1988256432 19.530 100 45 89 3 2008924384 7164496 13503456
21:17:59 63377 8192000000 10956182288 11395063808 2005451632 19.666 100 47 89 4 2029962160 11983968 12526560
21:20:08 142491 8192000000 10919075584 11383730176 1891370112 18.757 94 27 54 1 1914419280 15384624 7664544
21:23:39 30521 8192000000 10962513376 11420516352 2010200672 19.703 100 39 78 1 2041870480 18363968 13305840
21:25:14 43322 8192000000 10916398128 11396190208 1962882032 19.329 98 14 28 0 1991893456 24427040 4584384
21:27:49 89359 8192000000 10904853200 11377496064 1961600208 19.319 99 22 44 0 1986273552 17714064 6959280
21:30:35 47634 8192000000 10911286992 11384172544 1970535808 19.390 100 12 24 3 1983386400 10008240 2842352
21:32:27 55020 8192000000 10911218272 11386773504 1958793728 19.296 99 17 34 1 1976148256 13039408 4315120
21:34:25 38267 8192000000 10885353424 11333963776 1876011488 18.633 95 40 80 1 1899106144 11075680 12018976
21:36:04 39325 8192000000 10888993264 11366920192 1940878336 19.154 99 23 46 2 1958899920 11671120 6350464
21:37:44 81092 8192000000 10867221648 11330490368 1858983648 18.495 95 13 26 0 1873910624 11415328 3511648
21:40:16 121775 8192000000 10881447552 11354103808 1778915312 17.841 91 12 23 2 1799405600 17244320 3245968
21:43:19 86636 8192000000 10864553664 11332685824 1886964096 18.721 97 12 24 0 1907198784 17025056 3209632
21:45:46 77715 8192000000 10874938944 11347308544 1858656304 18.492 95 25 50 2 1877391744 11404176 7331264
21:48:58 54037 8192000000 10887169952 11338076160 1894569184 18.783 97 38 75 2 1923051520 17662368 10819968
21:51:10 36328 8192000000 10886207952 11361763328 1933969856 19.099 99 15 30 1 1953392544 14988592 4434096
21:52:47 78551 8192000000 10876220832 11349143552 1935225632 19.109 99 10 20 2 1948172992 10143328 2804032
21:55:06 67220 8192000000 10885011184 11361484800 1962822560 19.328 100 8 16 0 1974808080 9498944 2486576
21:57:39 29683 8192000000 10874367568 11360133120 1944787520 19.185 99 20 40 1 1962536864 12430048 5319296
21:59:09 73339 8192000000 10875550608 11351252992 1935289184 19.109 99 13 26 1 1947457792 8488560 3680048
22:01:30 57877 8192000000 10883429136 11369676800 1960555776 19.310 100 12 24 1 1975887184 11511616 3819792
22:03:44 74063 8192000000 10894474112 11372478464 1945808304 19.193 99 21 42 1 1964223376 12309648 6105424
22:06:00 68890 8192000000 10896686240 11375534080 1951483504 19.238 99 20 40 0 1968034224 10627104 5923616
22:08:11 32567 8192000000 10894411040 11360849920 1935861344 19.114 98 25 48 1 1950665328 8456016 6347968
22:09:47 34191 8192000000 10931899120 11398512640 1984269712 19.498 100 20 40 0 2000921360 10897360 5754288
22:12:04 138705 8192000000 10891535584 11381059584 1867008640 18.560 94 14 28 0 1883337360 11984864 4343856
22:15:29 140699 8192000000 10890063008 11360575488 1896743520 18.800 96 12 25 0 1910608400 11107120 2757760
22:18:51 32440 8192000000 10879193120 11315257344 1885665232 18.711 96 63 127 3 1911708800 7309056 18734512
22:20:24 84541 8192000000 10872440176 11366383616 1940957536 19.154 99 8 16 0 1954167408 11413264 1796608
22:22:59 44238 8192000000 10850166816 11326504960 1897431024 18.806 97 13 25 1 1915148688 14002736 3714928
22:24:45 63531 8192000000 10863310000 11333406720 1914838208 18.945 99 10 20 0 1927535104 10148320 2548576
22:26:51 32876 8192000000 10864230048 11331190784 1915637968 18.952 99 20 39 0 1933407536 12233392 5536176
22:28:30 87415 8192000000 10846484496 11325882368 1855057024 18.463 96 15 30 0 1880315392 20885872 4372496
22:30:58 88552 8192000000 10864403776 11334647808 1894194256 18.780 98 19 38 1 1912491040 12969040 5327744
22:33:59 35783 8192000000 10860103536 11324538880 1904214784 18.860 98 37 74 2 1927162624 12553152 10394688
22:35:52 95941 8192000000 10880739968 11358896128 1921449008 18.998 99 11 22 1 1934009616 9556320 3004288

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-08-2021 22:38:36 to 02-08-2021 22:38:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-08-2021 22:38:51 to 02-08-2021 22:38:53.

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          250624
MON$OLDEST_ACTIVE               251901
MON$OLDEST_SNAPSHOT             251901
MON$NEXT_TRANSACTION            251901
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                       2057888
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:52: 
:53: mscs-test-161.budzdorov.ru    Mon Aug  2 22:37:51 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Mon Aug  2 22:37:51 2021
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:59: 
--- end of diff output ---

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

02.08.21 22:38:54. 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-02 22:38:57 No severe PSQL-related problems occured
02.08.21 22:38:58. End of report.