Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.08.2021 19:37
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 16216364 kB
MemAvailable 30996248 kB
Buffers 0 kB
Cached 15408040 kB
SwapCached 2624 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-07 19:37:44
DTS_END                         2021-08-07 19:37:44.4370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48412
TRN_ID                          246285
ATT_ID                          711
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: 9694.66 17581 1163359
customer order: creation 182.83 353 21939
customer order: refuse 15.28 355 1834
order to supplier: creation 47.48 1265 5698
order to supplier: removal 3.45 98107 414
invoice (draft): creation 48.27 3302 5792
invoice (draft): removal 55.58 751 6670
invoice accept: apply 44.53 17075 5344
invoice accept: cancel 50.15 787 6018
customer reserve: creation 4079.18 170 489501
customer reserve: removal 4851.26 54 582151
realization accept: apply 89.20 157 10704
realization accept: cancel 89.87 97 10784
payment from customer: creation 62.69 106 7523
payment from customer: removal 6.50 63 780
payment to supplier: creation 51.58 111 6190
payment to supplier: removal 3.65 64 438
service: total inventory turnovers 4.88 1182 586
service: total monetary turnovers 7.19 24 863
service: aggregate perf. data 1.05 17134 126
service: refresh index statistics 0.03 210463 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3172
TEST_TIME 2 3736
TEST_TIME 3 4374
TEST_TIME 4 4590
TEST_TIME 5 4781
TEST_TIME 6 4956
TEST_TIME 7 5622
TEST_TIME 8 5939
TEST_TIME 9 6251
TEST_TIME 10 6510
TEST_TIME 11 6483
TEST_TIME 12 7074
TEST_TIME 13 7498
TEST_TIME 14 7512
TEST_TIME 15 7755
TEST_TIME 16 8234
TEST_TIME 17 8459
TEST_TIME 18 8780
TEST_TIME 19 9295
TEST_TIME 20 9307
TEST_TIME 21 9656
TEST_TIME 22 9872
TEST_TIME 23 9830
TEST_TIME 24 9774
TEST_TIME 25 9852
TEST_TIME 26 9691
TEST_TIME 27 9841
TEST_TIME 28 10361
TEST_TIME 29 10794
TEST_TIME 30 11196
TEST_TIME 31 10977
TEST_TIME 32 10898
TEST_TIME 33 10986
TEST_TIME 34 10895
TEST_TIME 35 10938
TEST_TIME 36 10921
TEST_TIME 37 10857
TEST_TIME 38 10655
TEST_TIME 39 10462
TEST_TIME 40 10578
TEST_TIME 41 10458
TEST_TIME 42 10396
TEST_TIME 43 10311
TEST_TIME 44 10293
TEST_TIME 45 10352
TEST_TIME 46 10360
TEST_TIME 47 10298
TEST_TIME 48 10171
TEST_TIME 49 10134
TEST_TIME 50 10091
TEST_TIME 51 9997
TEST_TIME 52 9877
TEST_TIME 53 9976
TEST_TIME 54 9881
TEST_TIME 55 9865
TEST_TIME 56 9854
TEST_TIME 57 9883
TEST_TIME 58 9811
TEST_TIME 59 9891
TEST_TIME 60 9881
TEST_TIME 61 9943
TEST_TIME 62 9958
TEST_TIME 63 10040
TEST_TIME 64 10031
TEST_TIME 65 10068
TEST_TIME 66 10005
TEST_TIME 67 10100
TEST_TIME 68 10066
TEST_TIME 69 9994
TEST_TIME 70 10184
TEST_TIME 71 10104
TEST_TIME 72 9987
TEST_TIME 73 9957
TEST_TIME 74 9935
TEST_TIME 75 9929
TEST_TIME 76 9920
TEST_TIME 77 9849
TEST_TIME 78 9820
TEST_TIME 79 9793
TEST_TIME 80 9722
TEST_TIME 81 9687
TEST_TIME 82 9667
TEST_TIME 83 9636
TEST_TIME 84 9612
TEST_TIME 85 9618
TEST_TIME 86 9626
TEST_TIME 87 9678
TEST_TIME 88 9636
TEST_TIME 89 9709
TEST_TIME 90 9664
TEST_TIME 91 9707
TEST_TIME 92 9756
TEST_TIME 93 9818
TEST_TIME 94 9802
TEST_TIME 95 9797
TEST_TIME 96 9886
TEST_TIME 97 9856
TEST_TIME 98 9827
TEST_TIME 99 9784
TEST_TIME 100 9740
TEST_TIME 101 9695
TEST_TIME 102 9720
TEST_TIME 103 9658
TEST_TIME 104 9636
TEST_TIME 105 9599
TEST_TIME 106 9564
TEST_TIME 107 9574
TEST_TIME 108 9567
TEST_TIME 109 9614
TEST_TIME 110 9616
TEST_TIME 111 9590
TEST_TIME 112 9586
TEST_TIME 113 9680
TEST_TIME 114 9651
TEST_TIME 115 9646
TEST_TIME 116 9631
TEST_TIME 117 9616
TEST_TIME 118 9710
TEST_TIME 119 9717
TEST_TIME 120 9700

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-08-2021 19:38:24 to 07-08-2021 19:38:27.

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 1614813 1614813 0 0.00 0 33493 6 0 0
doc_list_biud 1614813 1614813 0 0.00 0 49103 25 0 0
sp_add_invoice_to_stock 5513 5344 169 3.06 22 212216 17075 0 169
sp_cancel_adding_invoice 6018 6018 0 0.00 5 67027 788 0 0
sp_cancel_client_order 1834 1834 0 0.00 4 9456 356 0 0
sp_cancel_customer_reserve 582151 582151 0 0.00 1 49104 55 0 0
sp_cancel_pay_from_customer 780 780 0 0.00 3 2598 63 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 2 4087 65 0 0
sp_cancel_supplier_invoice 6686 6670 16 0.23 1 14847 752 0 16
sp_cancel_supplier_order 414 414 0 0.00 5 905145 98107 0 0
sp_cancel_write_off 10784 10784 0 0.00 1 13836 97 0 0
sp_client_order 21939 21939 0 0.00 3 24406 353 0 0
sp_customer_reserve 489501 489501 0 0.00 0 69773 171 0 0
sp_fill_shopping_cart 33411 33411 0 0.00 1 24768 35 0 0
sp_fill_shopping_cart_clo_res 489489 489489 0 0.00 0 16657 13 0 0
sp_get_clo_for_invoice 5332 5332 0 0.00 0 21473 309 0 0
sp_kill_cost_storno 18020 18020 0 0.00 0 33481 53 0 0
sp_kill_qstorno_ret_qs2qd 1182170 1182170 0 0.00 0 49101 30 0 0
sp_kill_qty_storno 623907 623907 0 0.00 0 49103 64 0 0
sp_lock_selected_doc 22215 22215 0 0.00 0 7518 4 0 0
sp_make_cost_storno 29749 29749 0 0.00 0 24361 45 0 0
sp_multiply_rows_for_pdistr 29749 29749 0 0.00 0 7471 26 0 0
sp_multiply_rows_for_qdistr 21939 21939 0 0.00 0 12449 159 0 0
sp_pay_from_customer 7523 7523 0 0.00 4 13617 107 0 0
sp_pay_to_supplier 6190 6190 0 0.00 4 24366 112 0 0
sp_payment_common 13713 13713 0 0.00 1 7335 24 0 0
sp_qd_handle_on_cancel_clo 1834 1834 0 0.00 0 9449 323 0 0
sp_qd_handle_on_invoice_upd_sts 11350 11350 0 0.00 0 37798 313 0 0
sp_qd_handle_on_reserve_upd_sts 10704 10704 0 0.00 0 17700 53 0 0
sp_reserve_write_off 10704 10704 0 0.00 6 17747 157 0 0
sp_supplier_invoice 5792 5792 0 0.00 6 80649 3302 0 0
sp_supplier_order 5698 5698 0 0.00 5 65910 1265 0 0
srv_aggregate_perf_data 126 126 0 0.00 2598 242879 17134 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2127 133229 47942 0 0
srv_find_qd_qs_mism 1111906 1111906 0 0.00 0 14286 5 0 0
srv_make_invnt_saldo 586 586 0 0.00 28 77932 1182 0 0
srv_make_money_saldo 863 863 0 0.00 1 651 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 172990 234460 210464 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5345 5345 0 0.00 0 24160 153 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 2912 95 0 0
t$perf-norm:sp_cancel_client_order 1834 1834 0 0.00 0 5927 18 0 0
t$perf-norm:sp_cancel_customer_reserve 756 756 0 0.00 0 521 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 780 780 0 0.00 0 592 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 593 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 4320 35 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 19021 1694 0 0
t$perf-norm:sp_cancel_write_off 1069 1069 0 0.00 0 3241 8 0 0
t$perf-norm:sp_client_order 21940 21940 0 0.00 0 13150 19 0 0
t$perf-norm:sp_customer_reserve 1074 1074 0 0.00 0 2577 13 0 0
t$perf-norm:sp_pay_from_customer 7523 7523 0 0.00 0 6647 12 0 0
t$perf-norm:sp_pay_to_supplier 6190 6190 0 0.00 0 6007 8 0 0
t$perf-norm:sp_reserve_write_off 10704 10704 0 0.00 0 5788 12 0 0
t$perf-norm:sp_supplier_invoice 5792 5792 0 0.00 0 7752 21 0 0
t$perf-norm:sp_supplier_order 5698 5698 0 0.00 0 8714 17 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 591 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1968 35 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 617 17 0 0
t$perf-norm:srv_make_money_saldo 863 863 0 0.00 0 1261 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 4 0 0
v_add_invoice_to_stock 11002 10664 338 3.07 0 9417 19 0 338
v_all_customers 39880 39880 0 0.00 0 46 1 0 0
v_all_suppliers 31774 31774 0 0.00 0 52 1 0 0
v_all_wares 43878 43878 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 46 5 0 0
v_cancel_client_order 3668 3668 0 0.00 0 3216 7 0 0
v_cancel_customer_prepayment 1560 1560 0 0.00 0 2586 7 0 0
v_cancel_customer_reserve 1512 1512 0 0.00 0 2490 7 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 766 4 0 0
v_cancel_supplier_invoice 912 880 32 3.50 0 3433 26 0 32
v_cancel_supplier_order 828 828 0 0.00 0 1099 5 0 0
v_cancel_write_off 2138 2138 0 0.00 0 1619 13 0 0
v_max_id_clo_ord 5695 5695 0 0.00 0 2058 1 0 0
v_max_id_clo_res 1074 1074 0 0.00 0 3452 15 0 0
v_max_id_ord_sup 5777 5777 0 0.00 0 5828 5 0 0
v_max_non_paid_invoice 1775 1775 0 0.00 0 1089 3 0 0
v_max_non_paid_realizn 4061 4061 0 0.00 0 4729 5 0 0
v_min_id_clo_ord 5695 5695 0 0.00 0 2079 4 0 0
v_min_id_clo_res 1074 1074 0 0.00 0 4989 9 0 0
v_min_id_ord_sup 5777 5777 0 0.00 0 5381 8 0 0
v_min_non_paid_invoice 6190 6190 0 0.00 0 6112 13 0 0
v_min_non_paid_realizn 7523 7523 0 0.00 0 5459 14 0 0
v_our_firm 10922 10922 0 0.00 0 54 1 0 0
v_reserve_write_off 21408 21408 0 0.00 0 6475 7 0 0
x_lock_depdocs_on_canc_invoice 12248 12248 0 0.00 0 24188 115 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 11946 384 0 0
x_make_qstorno_1000_1200 5698 5698 0 0.00 2 65885 1189 0 0
x_make_qstorno_1200_2000 5792 5792 0 0.00 2 80095 3170 0 0
x_make_qstorno_2100_3300 489501 489501 0 0.00 0 69771 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-08-2021 19:38:28 to 07-08-2021 19:38:32.

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:48 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:48 26978 8192000000 10377406672 10693410816 1421426736 14.785 100 87 174 0 1456210576 6145120 28638720
17:10:18 15723 8192000000 10418916272 10749517824 1478213488 15.286 100 85 170 2 1513876736 8177152 27486096
17:11:38 15260 8192000000 10461068576 10802692096 1521047504 15.659 100 75 150 0 1553802784 9233632 23521648
17:12:57 10196 8192000000 10532462672 10884874240 1592888272 16.279 100 77 155 0 1626539904 10376592 23275040
17:14:11 10904 8192000000 10605034176 10972237824 1669220560 16.927 100 75 150 0 1702259248 11074784 21963904
17:15:27 8730 8192000000 10676373872 11054465024 1741740688 17.533 100 73 146 0 1773683152 11496832 20445632
17:16:37 2879 8192000000 10741131424 11123359744 1803053904 18.039 100 79 157 0 1837202656 12910688 21238064
17:17:41 2939 8192000000 10782078896 11170877440 1844963056 18.381 100 84 168 0 1881552512 12033376 24556080
17:18:48 2167 8192000000 10813009824 11206045696 1870320624 18.587 100 92 185 3 1910217536 13578752 26318160
17:19:52 4789 8192000000 10818048112 11216822272 1877850784 18.648 100 62 125 1 1905763760 10520016 17392960
17:20:59 4439 8192000000 10810229280 11223752704 1887211728 18.723 100 38 76 1 1905712704 7835584 10665392
17:22:04 7455 8192000000 10807430912 11240206336 1900990032 18.834 100 18 36 2 1912296928 6281008 5025888
17:23:14 51452 8192000000 10826965744 11259174912 1913738944 18.937 100 33 66 1 1929401248 5960608 9701696
17:25:06 4400 8192000000 10832754272 11267280896 1921008240 18.995 100 28 56 2 1935524256 6852768 7663248
17:26:12 11062 8192000000 10830085360 11272335360 1921097328 18.996 100 25 50 0 1934407824 6031136 7279360
17:27:28 36740 8192000000 10814814368 11258507264 1896825904 18.801 99 26 51 1 1909966000 5441072 7699024
17:29:26 6089 8192000000 10788245152 11208593408 1846276368 18.392 99 31 62 0 1861658864 6879296 8503200
17:30:38 10385 8192000000 10690964976 11093209088 1665606400 16.896 92 25 49 1 1678338816 4655040 8077376
17:31:50 16982 8192000000 10681974112 11097186304 1700253520 17.187 94 27 54 2 1714246800 6073568 7919712
17:33:07 24698 8192000000 10738073424 11159777280 1799910976 18.013 99 25 50 0 1812702688 5636432 7155280
17:34:32 8199 8192000000 10753004832 11171799040 1821808464 18.192 99 33 66 0 1838343824 6202432 10332928
17:35:42 11437 8192000000 10780332560 11203379200 1857157520 18.480 100 19 36 0 1868796160 6140336 5498304
17:36:54 14233 8192000000 10802498000 11224940544 1878247104 18.651 100 23 46 3 1890281184 6128160 5905920
17:38:09 24832 8192000000 10837696672 11260116992 1913672272 18.936 100 46 90 1 1934717584 7057008 13988304
17:39:41 17926 8192000000 10882636384 11304050688 1948067024 19.211 100 44 89 1 1970824256 10172848 12584384
17:41:01 37951 8192000000 10906163520 11336536064 1961337936 19.317 100 42 83 2 1986842560 12771744 12732880
17:43:00 10297 8192000000 10923733664 11350745088 1983268416 19.491 100 53 104 1 2011214000 12731760 15213824
17:44:14 24732 8192000000 10930462464 11367714816 1989430384 19.539 100 44 84 4 2019431344 16850752 13150208
17:45:42 2127 8192000000 10972975200 11390889984 2012965216 19.725 100 69 136 2 2050908048 17921152 20021680
17:46:49 5430 8192000000 10974900432 11399516160 2019813504 19.779 100 74 148 1 2064201808 22246704 22141600
17:47:58 10807 8192000000 10978592352 11412041728 2019552240 19.777 100 62 124 1 2066104624 27519056 19033328
17:49:14 2786 8192000000 10976984544 11402592256 1998665280 19.612 99 77 154 3 2050288928 27904704 23718944
17:50:22 3520 8192000000 10994016304 11423236096 2013257776 19.727 99 67 135 0 2062879536 29373152 20248608
17:51:32 5802 8192000000 10983655488 11416236032 2003581584 19.651 99 63 126 1 2054560976 31441920 19537472
17:52:43 4054 8192000000 10979224048 11410079744 1994666784 19.581 99 73 146 1 2054920240 37765728 22487728
17:53:52 8959 8192000000 10928453440 11375108096 1960498144 19.310 99 36 72 1 2006896304 34574528 11823632
17:55:07 5733 8192000000 10954728816 11387318272 1952582784 19.247 98 59 118 1 2012104624 38964656 20557184
17:56:16 9279 8192000000 10921676112 11361263616 1936550048 19.119 99 42 83 3 1990624816 40626000 13448768
17:57:33 15701 8192000000 10905062432 11350999040 1944324800 19.181 100 36 72 0 1994232976 38148640 11759536
17:58:53 16006 8192000000 10927244160 11364061184 1958070576 19.291 100 32 62 0 2007235680 37870288 11294816
18:00:14 13718 8192000000 10930783344 11371290624 1954828928 19.265 99 46 91 2 2004247232 34867360 14550944
18:01:31 9580 8192000000 10937300032 11383185408 1972577152 19.406 100 46 92 0 2027637168 40632224 14427792
18:02:44 25805 8192000000 10927800576 11377856512 1965202800 19.347 100 18 36 2 2009974272 38486176 6285296
18:04:13 29910 8192000000 10918904208 11374374912 1952624480 19.247 100 25 50 4 2008136992 46565264 8947248
18:05:48 44009 8192000000 10899867888 11359375360 1941776384 19.161 99 19 38 3 1982826848 34722192 6328272
18:07:34 34677 8192000000 10895807168 11340955648 1944476384 19.182 100 17 35 1 1961218880 11190112 5552384
18:09:11 89620 8192000000 10892800272 11350908928 1910076368 18.907 97 20 40 3 1933684592 17110480 6497744
18:11:47 71324 8192000000 10878680944 11349635072 1918198368 18.972 98 28 54 1 1950106864 23487952 8420544
18:13:59 125553 8192000000 10885458848 11338043392 1945287136 19.189 99 29 56 0 1966451792 13235376 7929280
18:17:10 133229 8192000000 10894240544 11380285440 1937129712 19.124 98 18 36 0 1962032368 19491936 5410720
18:20:38 54302 8192000000 10903506240 11365765120 1962405024 19.325 99 21 42 0 1980382816 11059696 6918096
18:22:38 26963 8192000000 10903094880 11365629952 1940985520 19.155 98 33 65 0 1965394960 14771776 9637664
18:24:08 75090 8192000000 10904688400 11380166656 1978920176 19.456 100 12 24 0 1996337584 14366528 3050880
18:26:24 85727 8192000000 10901354704 11386974208 1990164896 19.545 100 18 35 4 2003712928 9141968 4406064
18:28:51 97265 8192000000 10916778320 11412582400 1994297120 19.578 100 8 16 0 2013146848 16594080 2255648
18:31:30 63537 8192000000 10936468240 11423121408 2015249072 19.743 100 21 42 1 2035192304 13456992 6486240
18:33:34 83121 8192000000 10920654928 11404140544 1968012528 19.370 98 3 6 0 1980035888 11066832 956528
18:36:06 54012 8192000000 10901367232 11382497280 1943416192 19.174 97 13 26 0 1961224400 14087488 3720720
18:38:00 50743 8192000000 10900629504 11377979392 1953857968 19.257 98 20 40 3 1971495728 12611568 5026192
18:39:58 70966 8192000000 10900452000 11378126848 1888956288 18.737 95 12 24 1 1907145520 15053376 3135856
18:42:10 52319 8192000000 10876402736 11323392000 1848792208 18.412 94 23 46 3 1863075328 7387344 6895776
18:44:02 20129 8192000000 10867313376 11315040256 1893537616 18.774 98 36 72 2 1917915760 14495568 9882576
18:45:54 50742 8192000000 10875580256 11328761856 1884593184 18.702 97 21 42 1 1903931632 13079488 6258960
18:47:47 83016 8192000000 10877455104 11348508672 1910244992 18.909 98 18 36 0 1934939264 19517024 5177248
18:50:10 69185 8192000000 10863367056 11331612672 1924347600 19.022 99 37 74 1 1947922576 12521808 11053168
18:52:24 57341 8192000000 10859056928 11323183104 1885244704 18.707 97 18 36 2 1898771840 8352640 5174496
18:55:39 39381 8192000000 10867858512 11338653696 1949564720 19.223 100 18 36 1 1961614720 7218016 4831984
18:57:21 81652 8192000000 10845955216 11324760064 1903024464 18.851 98 12 24 2 1914075040 7835552 3215024
18:59:45 86726 8192000000 10871897984 11336228864 1892528640 18.766 97 18 36 0 1907591712 9323136 5739936
19:02:14 37992 8192000000 10863925920 11330932736 1879712176 18.663 96 29 56 3 1895469872 7317168 8440528
19:03:55 72020 8192000000 10889216720 11368378368 1924917424 19.026 98 10 20 0 1940786784 13101216 2768144
19:06:10 106405 8192000000 10938609936 11402883072 1969320048 19.380 99 22 44 1 1989958800 14553072 6085680
19:08:57 85898 8192000000 10943593712 11404644352 1966509968 19.358 98 13 26 1 1986389440 16648640 3230832
19:11:33 35092 8192000000 10961502960 11416707072 2001488336 19.634 99 44 88 0 2023666976 9945152 12233488
19:13:10 77381 8192000000 10926251968 11401588736 1942193328 19.164 97 14 28 0 1960759840 14910608 3655904
19:15:33 95845 8192000000 10938423200 11413176320 2011945248 19.717 100 23 46 0 2026739696 7682832 7111616
19:18:14 10129 8192000000 10916012832 11381870592 1940904688 19.154 97 37 70 2 1961742656 10494288 10343680
19:19:26 59275 8192000000 10917750192 11400912896 1977420576 19.444 99 13 26 0 1992633856 11201472 4011808
19:21:25 62671 8192000000 10884978176 11359162368 1922990176 19.011 96 18 35 2 1943228128 15549696 4688256
19:23:28 76690 8192000000 10905094592 11387031552 1968853136 19.376 99 16 32 0 1988993440 15054848 5085456
19:25:51 62222 8192000000 10900381456 11388526592 1967092224 19.362 99 14 28 0 1981609168 10335296 4181648
19:27:57 62063 8192000000 10904131936 11376611328 1914731728 18.945 96 27 53 1 1934479744 11900672 7847344
19:30:46 74082 8192000000 10894467040 11356811264 1905887040 18.874 97 18 36 1 1922198320 10199904 6111376
19:33:08 19301 8192000000 10880717632 11340156928 1917379504 18.966 98 37 72 1 1938869216 11691216 9798496
19:34:37 19421 8192000000 10893295168 11343552512 1931977216 19.083 100 47 94 0 1963163728 18258608 12927904
19:36:00 78276 8192000000 10844661120 11307823104 1819106512 18.170 94 17 34 0 1843079104 19647024 4325568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-08-2021 19:38:34 to 07-08-2021 19:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 07-08-2021 19:38:50 to 07-08-2021 19:38:51.

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          245462
MON$OLDEST_ACTIVE               246851
MON$OLDEST_SNAPSHOT             246851
MON$NEXT_TRANSACTION            246851
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                       2038304
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 ---
:347: 
:348: mscs-test-161.budzdorov.ru    Sat Aug  7 19:37:50 2021
:349:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:350: 
--- end of diff output ---

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

07.08.21 19:38:53. 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-07 19:38:55 No severe PSQL-related problems occured
07.08.21 19:38:56. End of report.