Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2021 01: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 15733092 kB
MemAvailable 30739236 kB
Buffers 0 kB
Cached 15914552 kB
SwapCached 1628 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.8
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-09-25 01:37:51
DTS_END                         2021-09-25 01:37:51.6470
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/46006
TRN_ID                          247965
ATT_ID                          719
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: 9345.86 25614 1121503
customer order: creation 183.12 364 21974
customer order: refuse 15.39 378 1847
order to supplier: creation 48.67 1232 5840
order to supplier: removal 3.59 105269 431
invoice (draft): creation 47.22 3482 5666
invoice (draft): removal 51.72 822 6206
invoice accept: apply 43.40 19892 5208
invoice accept: cancel 47.53 978 5703
customer reserve: creation 3990.33 197 478839
customer reserve: removal 4608.87 65 553064
realization accept: apply 85.87 190 10304
realization accept: cancel 82.55 119 9906
payment from customer: creation 63.85 130 7662
payment from customer: removal 6.03 102 723
payment to supplier: creation 51.02 140 6122
payment to supplier: removal 3.76 105 451
service: total inventory turnovers 4.95 1365 594
service: total monetary turnovers 7.02 77 842
service: aggregate perf. data 0.98 17825 117
service: refresh index statistics 0.03 359548 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-09-2021 01:38:37 to 25-09-2021 01:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6957
TEST_TIME 2 6041
TEST_TIME 3 5671
TEST_TIME 4 6233
TEST_TIME 5 6664
TEST_TIME 6 6062
TEST_TIME 7 6112
TEST_TIME 8 5986
TEST_TIME 9 6225
TEST_TIME 10 6397
TEST_TIME 11 6331
TEST_TIME 12 6495
TEST_TIME 13 6914
TEST_TIME 14 7368
TEST_TIME 15 7933
TEST_TIME 16 8279
TEST_TIME 17 8398
TEST_TIME 18 8444
TEST_TIME 19 8521
TEST_TIME 20 8890
TEST_TIME 21 8977
TEST_TIME 22 8969
TEST_TIME 23 8997
TEST_TIME 24 9060
TEST_TIME 25 9309
TEST_TIME 26 9425
TEST_TIME 27 9508
TEST_TIME 28 9637
TEST_TIME 29 9843
TEST_TIME 30 9929
TEST_TIME 31 10155
TEST_TIME 32 10133
TEST_TIME 33 11092
TEST_TIME 34 11288
TEST_TIME 35 11128
TEST_TIME 36 10896
TEST_TIME 37 10834
TEST_TIME 38 10809
TEST_TIME 39 10780
TEST_TIME 40 10644
TEST_TIME 41 10523
TEST_TIME 42 10389
TEST_TIME 43 10302
TEST_TIME 44 10244
TEST_TIME 45 10129
TEST_TIME 46 10029
TEST_TIME 47 10009
TEST_TIME 48 9948
TEST_TIME 49 9935
TEST_TIME 50 9749
TEST_TIME 51 9787
TEST_TIME 52 9725
TEST_TIME 53 9713
TEST_TIME 54 9592
TEST_TIME 55 9591
TEST_TIME 56 9615
TEST_TIME 57 9547
TEST_TIME 58 9488
TEST_TIME 59 9403
TEST_TIME 60 9293
TEST_TIME 61 9237
TEST_TIME 62 9320
TEST_TIME 63 9330
TEST_TIME 64 9311
TEST_TIME 65 9346
TEST_TIME 66 9442
TEST_TIME 67 9461
TEST_TIME 68 9475
TEST_TIME 69 9568
TEST_TIME 70 9546
TEST_TIME 71 9551
TEST_TIME 72 9561
TEST_TIME 73 9612
TEST_TIME 74 9610
TEST_TIME 75 9622
TEST_TIME 76 9556
TEST_TIME 77 9464
TEST_TIME 78 9596
TEST_TIME 79 9562
TEST_TIME 80 9492
TEST_TIME 81 9466
TEST_TIME 82 9462
TEST_TIME 83 9422
TEST_TIME 84 9356
TEST_TIME 85 9300
TEST_TIME 86 9347
TEST_TIME 87 9330
TEST_TIME 88 9333
TEST_TIME 89 9335
TEST_TIME 90 9325
TEST_TIME 91 9334
TEST_TIME 92 9378
TEST_TIME 93 9383
TEST_TIME 94 9378
TEST_TIME 95 9382
TEST_TIME 96 9400
TEST_TIME 97 9440
TEST_TIME 98 9438
TEST_TIME 99 9474
TEST_TIME 100 9513
TEST_TIME 101 9540
TEST_TIME 102 9510
TEST_TIME 103 9448
TEST_TIME 104 9404
TEST_TIME 105 9375
TEST_TIME 106 9399
TEST_TIME 107 9378
TEST_TIME 108 9351
TEST_TIME 109 9310
TEST_TIME 110 9264
TEST_TIME 111 9275
TEST_TIME 112 9288
TEST_TIME 113 9310
TEST_TIME 114 9299
TEST_TIME 115 9309
TEST_TIME 116 9305
TEST_TIME 117 9333
TEST_TIME 118 9364
TEST_TIME 119 9352
TEST_TIME 120 9359

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-09-2021 01:38:41 to 25-09-2021 01:38:44.

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 1565130 1565130 0 0.00 0 16368 7 0 0
doc_list_biud 1565106 1565106 0 0.00 0 52078 29 0 0
sp_add_invoice_to_stock 5294 5208 86 1.62 39 223499 19892 0 86
sp_cancel_adding_invoice 5703 5703 0 0.00 5 72046 978 0 0
sp_cancel_client_order 1847 1847 0 0.00 4 12872 379 0 0
sp_cancel_customer_reserve 553064 553064 0 0.00 1 52081 66 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 2 9801 103 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 5730 105 0 0
sp_cancel_supplier_invoice 6209 6206 3 0.04 2 26853 823 0 3
sp_cancel_supplier_order 431 431 0 0.00 7 1052971 105270 0 0
sp_cancel_write_off 9906 9906 0 0.00 1 8972 120 0 0
sp_client_order 21974 21974 0 0.00 4 49021 364 0 0
sp_customer_reserve 478839 478839 0 0.00 0 63449 197 0 0
sp_fill_shopping_cart 33470 33470 0 0.00 1 8196 40 0 0
sp_fill_shopping_cart_clo_res 478821 478821 0 0.00 0 17786 13 0 0
sp_get_clo_for_invoice 5186 5186 0 0.00 0 31469 294 0 0
sp_kill_cost_storno 16783 16783 0 0.00 0 9795 63 0 0
sp_kill_qstorno_ret_qs2qd 1122671 1122671 0 0.00 0 52074 35 0 0
sp_kill_qty_storno 592642 592642 0 0.00 0 52078 76 0 0
sp_lock_selected_doc 21668 21668 0 0.00 0 6775 5 0 0
sp_make_cost_storno 29274 29274 0 0.00 0 16357 60 0 0
sp_multiply_rows_for_pdistr 29270 29270 0 0.00 0 9051 31 0 0
sp_multiply_rows_for_qdistr 21974 21974 0 0.00 0 49010 160 0 0
sp_pay_from_customer 7662 7662 0 0.00 4 37343 131 0 0
sp_pay_to_supplier 6122 6122 0 0.00 5 13768 141 0 0
sp_payment_common 13783 13783 0 0.00 1 37325 38 0 0
sp_qd_handle_on_cancel_clo 1847 1847 0 0.00 0 12863 351 0 0
sp_qd_handle_on_invoice_upd_sts 10887 10887 0 0.00 0 24043 366 0 0
sp_qd_handle_on_reserve_upd_sts 10301 10301 0 0.00 0 10490 56 0 0
sp_reserve_write_off 10304 10304 0 0.00 6 19200 190 0 0
sp_supplier_invoice 5666 5666 0 0.00 6 105516 3483 0 0
sp_supplier_order 5840 5840 0 0.00 5 48074 1232 0 0
srv_aggregate_perf_data 117 117 0 0.00 2884 219618 17826 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1897 117716 41578 0 0
srv_find_qd_qs_mism 1071409 1071409 0 0.00 0 11921 6 0 0
srv_make_invnt_saldo 594 594 0 0.00 41 94350 1365 0 0
srv_make_money_saldo 842 842 0 0.00 1 37528 77 0 0
srv_recalc_idx_stat 4 4 0 0.00 79874 589851 359548 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5208 5208 0 0.00 0 18923 182 0 0
t$perf-norm:sp_cancel_adding_invoice 475 475 0 0.00 0 4174 99 0 0
t$perf-norm:sp_cancel_client_order 1847 1847 0 0.00 0 2608 13 0 0
t$perf-norm:sp_cancel_customer_reserve 758 758 0 0.00 0 2381 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 1253 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 552 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 4691 24 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 30904 1571 0 0
t$perf-norm:sp_cancel_write_off 1102 1102 0 0.00 0 3605 7 0 0
t$perf-norm:sp_client_order 21974 21974 0 0.00 0 14404 20 0 0
t$perf-norm:sp_customer_reserve 1090 1090 0 0.00 0 5096 9 0 0
t$perf-norm:sp_pay_from_customer 7662 7662 0 0.00 0 7112 10 0 0
t$perf-norm:sp_pay_to_supplier 6122 6122 0 0.00 0 12164 16 0 0
t$perf-norm:sp_reserve_write_off 10304 10304 0 0.00 0 12115 12 0 0
t$perf-norm:sp_supplier_invoice 5666 5666 0 0.00 0 14263 23 0 0
t$perf-norm:sp_supplier_order 5840 5840 0 0.00 0 7326 18 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 389 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 214 5 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 5516 36 0 0
t$perf-norm:srv_make_money_saldo 842 842 0 0.00 0 1495 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10538 10366 172 1.63 0 6176 20 0 172
v_all_customers 40644 40644 0 0.00 0 37 1 0 0
v_all_suppliers 31882 31882 0 0.00 0 85 1 0 0
v_all_wares 43944 43944 0 0.00 0 83 1 0 0
v_cancel_adding_invoice 950 950 0 0.00 0 3013 13 0 0
v_cancel_client_order 3694 3694 0 0.00 0 1964 7 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 2128 10 0 0
v_cancel_customer_reserve 1516 1516 0 0.00 0 2976 9 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 1828 8 0 0
v_cancel_supplier_invoice 798 792 6 0.75 0 4214 22 0 6
v_cancel_supplier_order 862 862 0 0.00 0 2169 10 0 0
v_cancel_write_off 2204 2204 0 0.00 0 3505 17 0 0
v_max_id_clo_ord 5837 5837 0 0.00 0 351 1 0 0
v_max_id_clo_res 1090 1090 0 0.00 0 4777 20 0 0
v_max_id_ord_sup 5660 5660 0 0.00 0 4212 5 0 0
v_max_non_paid_invoice 1677 1677 0 0.00 0 720 3 0 0
v_max_non_paid_realizn 3839 3839 0 0.00 0 3678 4 0 0
v_min_id_clo_ord 5837 5837 0 0.00 0 6103 9 0 0
v_min_id_clo_res 1090 1090 0 0.00 0 2794 8 0 0
v_min_id_ord_sup 5660 5660 0 0.00 0 6506 4 0 0
v_min_non_paid_invoice 6121 6121 0 0.00 0 7394 23 0 0
v_min_non_paid_realizn 7662 7662 0 0.00 0 9767 22 0 0
v_our_firm 10946 10946 0 0.00 0 15 1 0 0
v_reserve_write_off 20602 20602 0 0.00 0 5843 7 0 0
x_lock_depdocs_on_canc_invoice 11513 11513 0 0.00 0 12918 132 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 12066 461 0 0
x_make_qstorno_1000_1200 5840 5840 0 0.00 2 47963 1144 0 0
x_make_qstorno_1200_2000 5666 5666 0 0.00 3 105313 3342 0 0
x_make_qstorno_2100_3300 478839 478839 0 0.00 0 63447 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2021 01:38:46 to 25-09-2021 01:38:50.

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:55 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:55 12526 8192000000 10378423616 10696921088 1432135392 14.880 100 89 178 0 1467332912 5680880 29516640
23:10:20 20555 8192000000 10441895040 10773110784 1494949520 15.432 100 94 188 0 1534455008 9119168 30386320
23:11:48 33006 8192000000 10497039600 10841444352 1538688960 15.812 100 81 162 1 1575057712 10799824 25568928
23:13:27 17414 8192000000 10571591232 10928037888 1628204512 16.580 100 88 175 0 1666365712 11044096 27117104
23:14:47 8910 8192000000 10648812768 11021533184 1713540944 17.298 100 80 161 2 1749674944 12826640 23307360
23:15:59 4372 8192000000 10714932176 11089702912 1772641904 17.789 100 76 151 1 1805150800 11599056 20909840
23:17:04 2468 8192000000 10759609104 11142037504 1820039024 18.178 100 89 178 1 1859609872 15043056 24527792
23:18:07 2317 8192000000 10801503920 11187728384 1860618032 18.508 100 92 184 0 1899190768 13029536 25543200
23:19:11 1409 8192000000 10813591936 11199664128 1870290432 18.587 100 94 189 1 1908309408 12196800 25822176
23:20:13 4981 8192000000 10785814368 11203584000 1873461584 18.612 100 28 56 0 1887896032 6306208 8128240
23:21:22 14126 8192000000 10788922336 11215032320 1880292832 18.667 100 15 26 1 1893960496 9727072 3940592
23:22:37 15363 8192000000 10830600336 11240017920 1891837280 18.761 100 23 43 5 1903216992 5998768 5380944
23:23:56 6778 8192000000 10852783264 11254861824 1908433824 18.894 100 83 167 3 1939143520 6055632 24654064
23:25:03 7122 8192000000 10812497520 11248414720 1904627632 18.863 100 29 58 0 1918637664 5471888 8538144
23:26:14 13183 8192000000 10822145280 11255783424 1894400560 18.781 99 24 48 0 1907185152 5682016 7102576
23:27:32 26442 8192000000 10802625968 11231211520 1871922816 18.600 98 39 78 1 1890192160 7055376 11213968
23:28:59 5958 8192000000 10766755040 11193634816 1812414464 18.116 97 25 50 1 1826163360 6017184 7731712
23:30:10 7328 8192000000 10701082656 11119329280 1749623136 17.598 97 30 61 0 1770099472 10963968 9512368
23:31:21 67264 8192000000 10696404400 11117359104 1697927440 17.168 93 29 58 1 1712082800 4927888 9227472
23:33:29 12595 8192000000 10749364896 11180589056 1826085424 18.227 99 28 55 4 1840654592 5632624 8936544
23:34:45 6511 8192000000 10766018640 11193417728 1853105536 18.447 100 18 36 2 1864548768 5684352 5758880
23:35:54 50568 8192000000 10802270336 11216384000 1864435680 18.539 100 25 48 1 1877092080 4660784 7995616
23:37:50 3278 8192000000 10833100288 11238318080 1898981600 18.818 100 45 85 0 1917364752 6564656 11818496
23:39:02 9120 8192000000 10862288784 11279986688 1925301392 19.029 100 57 115 1 1952541072 11119648 16120032
23:40:26 20490 8192000000 10879379440 11301773312 1938656592 19.136 100 52 104 1 1966154432 12552384 14945456
23:41:54 17513 8192000000 10886138160 11319648256 1951406624 19.238 100 50 100 3 1979184384 14175472 13602288
23:43:18 12854 8192000000 10910360960 11341791232 1973031984 19.409 100 48 97 0 2000556912 13438016 14086912
23:44:42 3823 8192000000 10920940976 11350831104 1982499344 19.484 100 55 110 4 2011051024 13302176 15249504
23:45:49 11548 8192000000 10922055888 11348340736 1977152592 19.442 100 56 111 1 2010734368 17035984 16545792
23:47:06 10561 8192000000 10924241472 11354804224 1974498928 19.421 100 49 97 3 2008277648 19710016 14068704
23:48:22 2967 8192000000 10915634576 11344904192 1972309328 19.404 100 48 95 1 2003613280 16541984 14761968
23:49:27 1895 8192000000 10957239472 11371532288 1991112448 19.553 100 84 168 2 2040825424 24229056 25483920
23:50:33 2321 8192000000 10942595488 11365666816 1981912864 19.480 100 69 133 1 2024682192 21967440 20801888
23:51:41 4242 8192000000 10922934672 11340619776 1946911648 19.202 99 81 160 0 1999732160 27615920 25204592
23:52:50 4021 8192000000 10884368976 11292958720 1912981952 18.931 99 72 144 2 1958933072 24253904 21697216
23:54:00 2209 8192000000 10928252048 11334995968 1949745360 19.224 100 78 155 0 1997807632 24899056 23163216
23:55:11 3656 8192000000 10940790624 11354136576 1965391984 19.349 100 84 163 2 2019465056 29870224 24202848
23:56:20 3589 8192000000 10939886752 11364134912 1973557552 19.414 100 73 147 1 2029201376 33145840 22497984
23:57:30 2989 8192000000 10943514960 11348185088 1953120192 19.251 99 84 168 1 2013465616 34378256 25967168
23:58:38 4655 8192000000 10928662832 11346915328 1957711472 19.288 100 66 131 1 2008754496 30734880 20308144
23:59:52 11067 8192000000 10922845040 11332362240 1940539248 19.151 100 70 138 1 2001227440 38871328 21816864
00:01:12 2698 8192000000 10977513824 11390898176 1987989136 19.528 100 79 156 2 2054976208 42751248 24235824
00:02:18 8884 8192000000 10978850544 11410907136 1982485136 19.484 99 57 114 4 2051087280 49606368 18995776
00:03:30 15971 8192000000 10946926832 11393187840 1976417472 19.436 100 41 81 3 2037773792 46911568 14444752
00:04:50 8143 8192000000 10956824016 11400765440 1972645184 19.406 99 40 81 3 2031555984 45104256 13806544
00:06:24 23828 8192000000 10941216000 11392204800 1966046160 19.354 99 25 50 0 2016176880 42188224 7942496
00:07:57 26336 8192000000 10955351712 11406413824 1982691760 19.486 100 24 48 0 2033230224 42579104 7959360
00:09:31 21859 8192000000 10944970752 11397144576 1938171616 19.132 99 18 36 3 2004350288 59416368 6762304
00:10:54 46840 8192000000 10909542336 11354173440 1968881488 19.377 100 32 63 1 2001951696 24063504 9006704
00:12:44 85704 8192000000 10918052544 11352068096 1966485280 19.358 100 48 90 2 1992420688 11824160 14111248
00:15:14 64502 8192000000 10920420704 11376082944 1979306688 19.459 100 27 55 0 1998348000 11586368 7454944
00:17:21 102037 8192000000 10907076656 11378098176 1972671488 19.407 99 28 51 0 1987636912 7031280 7934144
00:20:20 90004 8192000000 10893070224 11371425792 1964195792 19.339 99 18 36 0 1977579232 8420704 4962736
00:22:50 61847 8192000000 10906388096 11373703168 1980614464 19.470 100 14 28 0 1991574944 7116992 3843488
00:24:52 50776 8192000000 10898472928 11367903232 1947670816 19.208 98 25 46 3 1965508032 10797376 7039840
00:26:48 63272 8192000000 10888790416 11351494656 1945776816 19.193 99 13 23 1 1962011952 12637024 3598112
00:28:52 103287 8192000000 10888848672 11377774592 1924696448 19.024 97 9 18 0 1945103920 17851984 2555488
00:32:22 74056 8192000000 10875356384 11343548416 1903425376 18.854 96 24 48 0 1922404768 11460640 7518752
00:34:38 63802 8192000000 10888105856 11362557952 1924648704 19.024 97 22 44 0 1943541280 12593824 6298752
00:36:42 87553 8192000000 10881882912 11358650368 1950746224 19.232 99 11 22 0 1965151600 11428320 2977056
00:39:11 45344 8192000000 10896684704 11372617728 1944777200 19.185 98 9 18 0 1958300608 11242720 2280688
00:41:30 67994 8192000000 10879824432 11358732288 1880777504 18.671 95 21 43 0 1898126688 11887344 5461840
00:43:39 40005 8192000000 10882320784 11352481792 1882093520 18.682 96 14 28 0 1895356416 9519104 3743792
00:45:24 115416 8192000000 10856450576 11324911616 1807346560 18.074 92 14 28 2 1821054416 9852640 3855216
00:48:22 69673 8192000000 10911317840 11371192320 1944625344 19.184 99 24 48 0 1968488112 17288096 6574672
00:50:32 46642 8192000000 10892969280 11342241792 1899159840 18.820 97 28 56 1 1916138832 8672016 8306976
00:52:18 53843 8192000000 10918595712 11361812480 1952344192 19.245 99 25 50 1 1972003936 12757808 6901936
00:54:18 98943 8192000000 10890979296 11386781696 1954761712 19.264 99 25 50 1 1976920752 14982400 7176640
00:56:59 28627 8192000000 10896917984 11353075712 1943475840 19.174 99 30 60 0 1962423296 11070448 7877008
00:58:28 47763 8192000000 10848748704 11334008832 1904790656 18.865 98 5 10 1 1913726064 7899328 1036080
01:00:16 50015 8192000000 10882592832 11356184576 1951900624 19.242 99 19 38 0 1965497376 8256192 5340560
01:02:14 63390 8192000000 10889815088 11364638720 1923620512 19.016 97 16 32 0 1939210192 10584560 5005120
01:04:26 66481 8192000000 10889647184 11366842368 1947376240 19.206 99 7 14 0 1956436720 7144032 1916448
01:06:59 55337 8192000000 10922324400 11382140928 1964132192 19.339 99 34 65 0 1981300528 8204832 8963504
01:09:00 64700 8192000000 10911853456 11397992448 1958176368 19.292 98 19 38 0 1976464400 13269536 5018496
01:11:05 60624 8192000000 10920969184 11387846656 1955624992 19.271 98 21 42 0 1970955136 9362928 5967216
01:13:07 45885 8192000000 10934648752 11403526144 1986891424 19.519 99 48 96 1 2014637952 13946672 13799856
01:15:16 18412 8192000000 10905111600 11355721728 1919301712 18.981 97 53 106 0 1945385696 11117840 14966144
01:16:36 117716 8192000000 10893444416 11370213376 1887203728 18.723 95 13 27 0 1901324688 10792336 3328624
01:19:44 96361 8192000000 10889463808 11361062912 1946791440 19.201 99 20 40 1 1961638880 8862496 5984944
01:22:21 38238 8192000000 10904151856 11366486016 1933675808 19.096 98 29 58 1 1957399472 16050160 7673504
01:24:12 47500 8192000000 10871237072 11348680704 1943367936 19.174 99 11 22 0 1959708352 13636208 2704208
01:25:59 54604 8192000000 10846923968 11320430592 1866465008 18.556 95 13 26 0 1880787264 10378736 3943520
01:27:56 52739 8192000000 10876971136 11361402880 1914950928 18.946 98 17 34 0 1935475504 15743968 4780608
01:30:26 45266 8192000000 10849811584 11318910976 1839923232 18.340 94 17 34 0 1852332848 7380432 5029184
01:32:12 61650 8192000000 10868635280 11320803328 1861597616 18.516 96 20 40 1 1884557040 17552864 5406560
01:34:20 59084 8192000000 10879964208 11349057536 1906989392 18.882 98 23 46 1 1921965312 8325648 6650272
01:36:22 24122 8192000000 10887641056 11342901248 1933787488 19.097 99 41 82 1 1954511184 9779920 10943776
01:37:47 15081 8192000000 9176503520 9429872640 333279184 3.909 18 2 4 0 335477712 2070416 128112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-09-2021 01:38:52 to 25-09-2021 01:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-09-2021 01:39:08 to 25-09-2021 01:39:10.

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          248495
MON$OLDEST_ACTIVE               248797
MON$OLDEST_SNAPSHOT             248797
MON$NEXT_TRANSACTION            248797
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                       2063392
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

25.09.21 01:39:11. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-09-25 01:39:13 No severe PSQL-related problems occured
25.09.21 01:39:14. End of report.