Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.08.2021 01: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 16454396 kB
MemAvailable 31056504 kB
Buffers 0 kB
Cached 15238224 kB
SwapCached 2580 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-06 01:37:46
DTS_END                         2021-08-06 01:37:46.8760
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/49048
TRN_ID                          243589
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: 9159.54 17794 1099145
customer order: creation 179.88 339 21586
customer order: refuse 15.13 341 1815
order to supplier: creation 46.05 1311 5526
order to supplier: removal 3.43 95822 412
invoice (draft): creation 46.09 3669 5531
invoice (draft): removal 53.31 803 6397
invoice accept: apply 42.07 18219 5048
invoice accept: cancel 47.83 800 5739
customer reserve: creation 3800.25 185 456030
customer reserve: removal 4632.54 55 555905
realization accept: apply 84.16 186 10099
realization accept: cancel 79.34 102 9521
payment from customer: creation 59.36 108 7123
payment from customer: removal 5.78 72 693
payment to supplier: creation 49.71 147 5965
payment to supplier: removal 3.27 74 392
service: total inventory turnovers 4.31 1159 517
service: total monetary turnovers 6.18 29 741
service: aggregate perf. data 0.84 22540 101
service: refresh index statistics 0.03 209920 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-08-2021 01:39:42 to 06-08-2021 01:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2750
TEST_TIME 2 3444
TEST_TIME 3 3386
TEST_TIME 4 3364
TEST_TIME 5 3606
TEST_TIME 6 4187
TEST_TIME 7 4140
TEST_TIME 8 4690
TEST_TIME 9 4642
TEST_TIME 10 4793
TEST_TIME 11 5091
TEST_TIME 12 5322
TEST_TIME 13 5787
TEST_TIME 14 6142
TEST_TIME 15 6503
TEST_TIME 16 7258
TEST_TIME 17 7205
TEST_TIME 18 8503
TEST_TIME 19 8527
TEST_TIME 20 8597
TEST_TIME 21 8718
TEST_TIME 22 8994
TEST_TIME 23 8985
TEST_TIME 24 9183
TEST_TIME 25 9182
TEST_TIME 26 10065
TEST_TIME 27 10221
TEST_TIME 28 10557
TEST_TIME 29 10351
TEST_TIME 30 10178
TEST_TIME 31 10279
TEST_TIME 32 10284
TEST_TIME 33 10180
TEST_TIME 34 10062
TEST_TIME 35 9992
TEST_TIME 36 10021
TEST_TIME 37 9842
TEST_TIME 38 10034
TEST_TIME 39 10006
TEST_TIME 40 9841
TEST_TIME 41 9720
TEST_TIME 42 9695
TEST_TIME 43 9710
TEST_TIME 44 9633
TEST_TIME 45 9516
TEST_TIME 46 9413
TEST_TIME 47 9349
TEST_TIME 48 9329
TEST_TIME 49 9312
TEST_TIME 50 9252
TEST_TIME 51 9209
TEST_TIME 52 9168
TEST_TIME 53 9139
TEST_TIME 54 9125
TEST_TIME 55 9025
TEST_TIME 56 9138
TEST_TIME 57 9129
TEST_TIME 58 9126
TEST_TIME 59 9157
TEST_TIME 60 9122
TEST_TIME 61 9042
TEST_TIME 62 9301
TEST_TIME 63 9287
TEST_TIME 64 9326
TEST_TIME 65 9327
TEST_TIME 66 9347
TEST_TIME 67 9375
TEST_TIME 68 9393
TEST_TIME 69 9401
TEST_TIME 70 9384
TEST_TIME 71 9536
TEST_TIME 72 9524
TEST_TIME 73 9425
TEST_TIME 74 9324
TEST_TIME 75 9303
TEST_TIME 76 9287
TEST_TIME 77 9279
TEST_TIME 78 9227
TEST_TIME 79 9156
TEST_TIME 80 9186
TEST_TIME 81 9158
TEST_TIME 82 9118
TEST_TIME 83 9139
TEST_TIME 84 9140
TEST_TIME 85 9105
TEST_TIME 86 9116
TEST_TIME 87 9141
TEST_TIME 88 9241
TEST_TIME 89 9217
TEST_TIME 90 9278
TEST_TIME 91 9260
TEST_TIME 92 9260
TEST_TIME 93 9304
TEST_TIME 94 9275
TEST_TIME 95 9317
TEST_TIME 96 9285
TEST_TIME 97 9217
TEST_TIME 98 9234
TEST_TIME 99 9201
TEST_TIME 100 9181
TEST_TIME 101 9160
TEST_TIME 102 9168
TEST_TIME 103 9138
TEST_TIME 104 9085
TEST_TIME 105 9092
TEST_TIME 106 9076
TEST_TIME 107 9050
TEST_TIME 108 9100
TEST_TIME 109 9152
TEST_TIME 110 9135
TEST_TIME 111 9190
TEST_TIME 112 9159
TEST_TIME 113 9209
TEST_TIME 114 9220
TEST_TIME 115 9228
TEST_TIME 116 9221
TEST_TIME 117 9233
TEST_TIME 118 9170
TEST_TIME 119 9186
TEST_TIME 120 9167

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-08-2021 01:39:46 to 06-08-2021 01:39:49.

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 1522892 1522892 0 0.00 0 17965 6 0 0
doc_list_biud 1522892 1522892 0 0.00 0 82944 26 0 0
sp_add_invoice_to_stock 5213 5048 165 3.16 51 154439 18219 0 165
sp_cancel_adding_invoice 5739 5739 0 0.00 5 57379 800 0 0
sp_cancel_client_order 1815 1815 0 0.00 4 14060 341 0 0
sp_cancel_customer_reserve 555905 555905 0 0.00 1 67462 55 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 2 4166 73 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 3963 75 0 0
sp_cancel_supplier_invoice 6409 6397 12 0.18 1 82956 803 0 12
sp_cancel_supplier_order 412 412 0 0.00 10 1017898 95823 0 0
sp_cancel_write_off 9521 9521 0 0.00 1 14633 102 0 0
sp_client_order 21586 21586 0 0.00 3 46806 340 0 0
sp_customer_reserve 456030 456030 0 0.00 0 62269 185 0 0
sp_fill_shopping_cart 32640 32640 0 0.00 1 36140 38 0 0
sp_fill_shopping_cart_clo_res 456019 456019 0 0.00 0 48876 17 0 0
sp_get_clo_for_invoice 5037 5037 0 0.00 0 43341 334 0 0
sp_kill_cost_storno 16345 16345 0 0.00 0 8399 52 0 0
sp_kill_qstorno_ret_qs2qd 1128140 1128140 0 0.00 0 82938 30 0 0
sp_kill_qty_storno 594925 594925 0 0.00 0 82942 66 0 0
sp_lock_selected_doc 21038 21038 0 0.00 0 8418 4 0 0
sp_make_cost_storno 28224 28224 0 0.00 0 12604 55 0 0
sp_multiply_rows_for_pdistr 28224 28224 0 0.00 0 12472 27 0 0
sp_multiply_rows_for_qdistr 21586 21586 0 0.00 0 46798 153 0 0
sp_pay_from_customer 7123 7123 0 0.00 4 18075 109 0 0
sp_pay_to_supplier 5965 5965 0 0.00 4 13311 147 0 0
sp_payment_common 13088 13088 0 0.00 2 18044 35 0 0
sp_qd_handle_on_cancel_clo 1815 1815 0 0.00 0 14052 305 0 0
sp_qd_handle_on_invoice_upd_sts 10776 10776 0 0.00 0 21869 330 0 0
sp_qd_handle_on_reserve_upd_sts 10099 10099 0 0.00 0 17952 60 0 0
sp_reserve_write_off 10099 10099 0 0.00 6 18019 187 0 0
sp_supplier_invoice 5531 5531 0 0.00 6 117593 3669 0 0
sp_supplier_order 5526 5526 0 0.00 6 46844 1312 0 0
srv_aggregate_perf_data 101 101 0 0.00 2655 316091 22541 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1349 152288 50732 0 0
srv_find_qd_qs_mism 1051824 1051824 0 0.00 0 17963 6 0 0
srv_make_invnt_saldo 517 517 0 0.00 47 56353 1160 0 0
srv_make_money_saldo 741 741 0 0.00 1 5909 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 65977 446024 209920 0 0
t$perf-abend:sp_add_invoice_to_stock 165 165 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5051 5051 0 0.00 0 54885 161 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 0 2447 87 0 0
t$perf-norm:sp_cancel_client_order 1815 1815 0 0.00 0 2377 13 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 4380 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 593 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 2989 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 4695 36 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 25329 1575 0 0
t$perf-norm:sp_cancel_write_off 1066 1066 0 0.00 0 3919 12 0 0
t$perf-norm:sp_client_order 21586 21586 0 0.00 0 11261 19 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 3131 8 0 0
t$perf-norm:sp_pay_from_customer 7123 7123 0 0.00 0 11683 13 0 0
t$perf-norm:sp_pay_to_supplier 5965 5965 0 0.00 0 12304 12 0 0
t$perf-norm:sp_reserve_write_off 10099 10099 0 0.00 0 7607 15 0 0
t$perf-norm:sp_supplier_invoice 5531 5531 0 0.00 0 5949 17 0 0
t$perf-norm:sp_supplier_order 5526 5526 0 0.00 0 6738 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 122 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 517 517 0 0.00 0 6154 36 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 6816 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10402 10072 330 3.17 0 4800 17 0 330
v_all_customers 38846 38846 0 0.00 0 76 1 0 0
v_all_suppliers 30690 30690 0 0.00 0 89 1 0 0
v_all_wares 43172 43172 0 0.00 0 37 1 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 3732 16 0 0
v_cancel_client_order 3630 3630 0 0.00 0 10123 10 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 896 4 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 1155 4 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1995 8 0 0
v_cancel_supplier_invoice 908 884 24 2.64 0 1864 18 0 24
v_cancel_supplier_order 824 824 0 0.00 0 3534 9 0 0
v_cancel_write_off 2132 2132 0 0.00 0 3692 16 0 0
v_max_id_clo_ord 5525 5525 0 0.00 0 488 1 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 21274 33 0 0
v_max_id_ord_sup 5529 5529 0 0.00 0 8356 7 0 0
v_max_non_paid_invoice 1674 1674 0 0.00 0 179 2 0 0
v_max_non_paid_realizn 3883 3883 0 0.00 0 2179 3 0 0
v_min_id_clo_ord 5525 5525 0 0.00 0 5395 8 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 6589 18 0 0
v_min_id_ord_sup 5529 5529 0 0.00 0 9099 6 0 0
v_min_non_paid_invoice 5965 5965 0 0.00 0 11029 27 0 0
v_min_non_paid_realizn 7123 7123 0 0.00 0 18041 23 0 0
v_our_firm 10806 10806 0 0.00 0 66 1 0 0
v_reserve_write_off 20198 20198 0 0.00 0 11085 8 0 0
x_lock_depdocs_on_canc_invoice 11694 11694 0 0.00 0 20676 110 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 10821 457 0 0
x_make_qstorno_1000_1200 5526 5526 0 0.00 1 46805 1239 0 0
x_make_qstorno_1200_2000 5531 5531 0 0.00 2 115062 3523 0 0
x_make_qstorno_2100_3300 456030 456030 0 0.00 0 50526 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-08-2021 01:39:50 to 06-08-2021 01:39:54.

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
23:07:50 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:50 27804 8192000000 10363629312 10683461632 1415580320 14.733 100 82 165 1 1447855008 5738672 26536016
23:10:20 15486 8192000000 10421957008 10747748352 1478079120 15.285 100 92 184 0 1516440064 8521488 29839456
23:11:43 22884 8192000000 10484162080 10824486912 1532360800 15.757 100 84 167 2 1567940704 9363632 26216272
23:13:09 12922 8192000000 10548622704 10905141248 1608689920 16.414 100 79 159 1 1643925360 11396608 23838832
23:14:26 10187 8192000000 10630896928 10993717248 1680561232 17.022 100 85 170 1 1718261024 13654320 24045472
23:15:41 4492 8192000000 10688109280 11059109888 1747033456 17.577 100 84 167 3 1782681248 12501984 23145808
23:16:47 3599 8192000000 10740508896 11116302336 1798786848 18.004 100 84 166 2 1836030080 14396080 22847152
23:17:52 2244 8192000000 10778745104 11164258304 1834736816 18.298 100 86 170 0 1871066480 12250736 24078928
23:18:56 1755 8192000000 10795717872 11193696256 1859145552 18.496 100 82 164 1 1896095600 13880288 23069760
23:19:58 5871 8192000000 10780162720 11192557568 1861756976 18.518 100 36 72 0 1878589376 6860992 9971408
23:21:07 3423 8192000000 10790997824 11209547776 1877412752 18.644 100 42 83 5 1896697744 7082416 12202576
23:22:12 12098 8192000000 10785676592 11212898304 1879464400 18.661 100 18 36 0 1890855184 6205760 5185024
23:23:25 75038 8192000000 10800872080 11235160064 1896507088 18.798 100 21 42 0 1908289680 5389328 6393264
23:25:40 8202 8192000000 10820575776 11247652864 1899752480 18.824 100 34 68 0 1916474144 6793184 9928480
23:26:49 8625 8192000000 10824982048 11257655296 1903122080 18.851 100 29 57 2 1926481504 14931536 8427888
23:28:03 19634 8192000000 10759431296 11181056000 1709612384 17.265 90 20 38 0 1721066192 4971072 6482736
23:29:22 17411 8192000000 10763624016 11194150912 1796445744 17.985 96 23 46 1 1809425760 6511600 6468416
23:30:40 6844 8192000000 10706089760 11126824960 1727159840 17.412 95 20 40 0 1737948128 5006640 5781648
23:31:47 10429 8192000000 10701080240 11116290048 1742702752 17.541 97 16 32 1 1752190192 4792576 4694864
23:32:58 80891 8192000000 10752730672 11184582656 1840225072 18.343 100 39 77 1 1858221904 6246752 11750080
23:35:20 5444 8192000000 10777123984 11208118272 1863706352 18.533 100 28 56 0 1878277680 7448496 7122832
23:36:26 8325 8192000000 10775821584 11213639680 1869384560 18.579 100 17 34 3 1880235904 5700144 5151200
23:37:34 10701 8192000000 10813534656 11247472640 1898262016 18.812 100 23 46 1 1914454432 10316256 5876160
23:38:58 49947 8192000000 10867900880 11291570176 1928107872 19.052 100 54 109 3 1955257904 11057712 16092320
23:40:51 46592 8192000000 10888938432 11316170752 1950079728 19.227 100 48 95 2 1976856080 13655312 13121040
23:42:45 28359 8192000000 10903853616 11336499200 1965134576 19.347 100 43 84 1 1992699600 15243824 12321200
23:44:18 5684 8192000000 10939376160 11370401792 1994013056 19.575 100 54 108 1 2026472448 16423728 16035664
23:45:32 16628 8192000000 10940787936 11385585664 1985895104 19.511 99 51 102 0 2027426944 26164864 15366976
23:46:53 17208 8192000000 10965847024 11396984832 2007697728 19.683 100 45 90 0 2046326656 23683856 14945072
23:48:16 6274 8192000000 10961670960 11401814016 1997661248 19.604 99 48 96 2 2042513632 29849488 15002896
23:49:26 7528 8192000000 10993562240 11421462528 2022040560 19.796 100 76 152 1 2080296304 36441904 21813840
23:50:36 3357 8192000000 11006701920 11430719488 2027497248 19.839 100 76 152 0 2090771120 40173488 23100384
23:51:43 4919 8192000000 10967353216 11388375040 1977408384 19.444 98 64 128 0 2034855568 37773168 19674016
23:52:54 1889 8192000000 10919975408 11326996480 1926104048 19.036 99 89 173 3 1996087728 42547616 27436064
23:54:01 6701 8192000000 10922060000 11342045184 1945169936 19.188 100 68 136 2 2005547312 38497168 21880208
23:55:11 3004 8192000000 10944458080 11358515200 1956711744 19.280 100 81 162 3 2027619168 45692000 25215424
23:56:17 5625 8192000000 10875465920 11297587200 1909117808 18.900 99 51 102 1 1954395888 29551648 15726432
23:57:32 9907 8192000000 10875714048 11315879936 1906614448 18.879 99 33 66 1 1950443584 32880928 10948208
23:58:51 1349 8192000000 10917498464 11339333632 1942294656 19.165 100 74 148 0 1998486944 32349504 23842784
23:59:53 17353 8192000000 10896077920 11338936320 1941749696 19.161 100 22 44 0 1977664864 28693744 7221424
00:01:12 7784 8192000000 10910707424 11327766528 1916860208 18.962 99 51 100 3 1969274032 35999360 16414464
00:02:22 17367 8192000000 10893937168 11331010560 1910654464 18.912 99 33 66 2 1961066752 39889888 10522400
00:03:49 36301 8192000000 10874464608 11327148032 1919389024 18.982 100 19 38 0 1951092144 25005856 6697264
00:05:34 34696 8192000000 10868824512 11336941568 1942383376 19.166 100 18 36 0 1959575840 12122480 5069984
00:07:35 64424 8192000000 10897680592 11357274112 1949603472 19.223 99 22 42 1 1971627328 15540160 6483696
00:09:42 97504 8192000000 10904528672 11365867520 1948483824 19.214 98 13 26 1 1964865056 13006752 3374480
00:12:21 125927 8192000000 10920322208 11375181824 1970672448 19.391 99 34 68 1 2000580432 19874784 10033200
00:15:37 81469 8192000000 10914159584 11392622592 1992114240 19.560 100 24 47 4 2011391616 12173488 7103888
00:17:59 4631 8192000000 10932988784 11358842880 1983341856 19.491 99 72 140 3 2008038384 5836816 18859712
00:19:04 49116 8192000000 10921797440 11389366272 1999085792 19.616 100 34 68 2 2019485952 10417616 9982544
00:20:54 60428 8192000000 10932683968 11399241728 2008893312 19.693 100 14 28 1 2024459568 11522624 4043632
00:23:04 71331 8192000000 10916900416 11389841408 1966180160 19.355 98 18 36 1 1982997920 11565280 5252480
00:25:16 64704 8192000000 10950309792 11402219520 2010058400 19.702 100 18 36 2 2023047376 7518976 5470000
00:27:22 77274 8192000000 10949496192 11423301632 2024500352 19.815 100 19 35 0 2041261024 12098624 4662048
00:29:45 57802 8192000000 10954315056 11430912000 2032537152 19.879 100 27 54 2 2054965408 14052000 8376256
00:31:45 92165 8192000000 10949388096 11416723456 2001375920 19.634 99 21 42 2 2025774480 18122704 6275856
00:34:55 60042 8192000000 10917234752 11397398528 1960702560 19.312 97 11 22 0 1974901568 11283856 2915152
00:36:56 79617 8192000000 10937894976 11382161408 1909554400 18.903 95 28 56 1 1926982768 10025104 7403264
00:39:16 32949 8192000000 10883086208 11373137920 1936494240 19.119 98 14 28 0 1959371312 18879760 3997312
00:40:49 55722 8192000000 10911662624 11366076416 1925630176 19.032 97 32 64 1 1949160352 14460240 9069936
00:42:49 59774 8192000000 10888551744 11352150016 1871591296 18.597 95 24 48 1 1890296704 11907184 6798224
00:44:50 138830 8192000000 10882221296 11354718208 1844286112 18.376 94 18 36 1 1866252592 16636944 5329536
00:48:09 55664 8192000000 10893214336 11376025600 1961536896 19.318 100 14 28 0 1979688800 13854000 4297904
00:50:15 152287 8192000000 10886966672 11347791872 1937675088 19.128 99 18 36 1 1956503280 14043600 4784592
00:53:54 52280 8192000000 10832692816 11310645248 1835784240 18.306 94 16 29 0 1848115616 8312528 4018848
00:55:49 63506 8192000000 10866625856 11336916992 1905889488 18.874 98 10 20 1 1924053472 15314400 2849584
00:57:54 69228 8192000000 10871097056 11358117888 1947459216 19.206 100 9 17 0 1964609952 15165392 1985344
01:00:03 93346 8192000000 10880112304 11341926400 1900964256 18.834 97 19 38 0 1915069152 8747760 5357136
01:02:45 62338 8192000000 10893597312 11353468928 1908130688 18.892 97 16 32 1 1925605872 12801632 4673552
01:04:54 104476 8192000000 10911912768 11374641152 1958551520 19.295 99 15 30 2 1983878080 21046464 4280096
01:07:39 84319 8192000000 10922772464 11374862336 1947380208 19.206 98 19 38 1 1961811776 9655104 4776464
01:10:04 21920 8192000000 10921325040 11369160704 1937610976 19.128 97 36 72 1 1958571248 10980608 9979664
01:11:52 56575 8192000000 10927267488 11390205952 1976339888 19.436 99 35 70 2 1998899072 12465296 10093888
01:14:04 120682 8192000000 10902279712 11364761600 1973510736 19.413 99 24 48 1 1988635488 8753648 6371104
01:17:06 13692 8192000000 10947159888 11395895296 1995807632 19.590 100 46 89 1 2017098624 8328960 12962032
01:18:20 50307 8192000000 10905255376 11382788096 1984312192 19.499 100 9 18 2 1999791696 12583712 2895792
01:20:12 66667 8192000000 10905048336 11371593728 1970494528 19.389 99 7 14 0 1982230624 9676480 2059616
01:22:28 35801 8192000000 10920764592 11374997504 1962344144 19.325 99 14 28 0 1973653472 7087968 4221360
01:24:05 24659 8192000000 10883755040 11340816384 1919489392 18.983 98 27 54 2 1938474000 10762032 8222576
01:25:35 66156 8192000000 10887147856 11361951744 1888257008 18.732 96 21 42 0 1908917824 14952800 5708016
01:27:47 63505 8192000000 10863598704 11329220608 1886622512 18.719 97 19 38 1 1906955536 15467200 4865824
01:29:51 104016 8192000000 10842646624 11325005824 1874068656 18.617 97 33 66 0 1900971584 17656896 9246032
01:32:40 45880 8192000000 10863012128 11307532288 1887877728 18.729 98 29 58 1 1910171504 14070160 8223616
01:34:26 75266 8192000000 10879336816 11342319616 1873943248 18.616 96 35 69 3 1900511808 17158784 9409776
01:37:01 60534 8192000000 9998850064 10438742016 909544832 9.993 48 2 4 0 921955728 11804768 606128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-08-2021 01:39:56 to 06-08-2021 01:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 165 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-08-2021 01:40:12 to 06-08-2021 01:40:14.

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          243777
MON$OLDEST_ACTIVE               244011
MON$OLDEST_SNAPSHOT             244011
MON$NEXT_TRANSACTION            244011
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                       2018176
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 ---
:233: 
:234: mscs-test-161.budzdorov.ru    Fri Aug  6 01:38:04 2021
:235:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:236: 
:237: 
:238: mscs-test-161.budzdorov.ru    Fri Aug  6 01:38:06 2021
:239:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:240: 
:241: 
:242: mscs-test-161.budzdorov.ru    Fri Aug  6 01:38:06 2021
:243:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:244: 
--- end of diff output ---

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

06.08.21 01:40:15. 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-06 01:40:17 No severe PSQL-related problems occured
06.08.21 01:40:18. End of report.