Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.08.2021 19: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 16068848 kB
MemAvailable 30932712 kB
Buffers 0 kB
Cached 15588772 kB
SwapCached 1328 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-24 19:37:42
DTS_END                         2021-08-24 19:37:42.9840
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/34092
TRN_ID                          248772
ATT_ID                          684
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: 9538.82 19545 1144658
customer order: creation 180.58 312 21669
customer order: refuse 16.33 317 1959
order to supplier: creation 48.74 1281 5849
order to supplier: removal 3.68 85890 441
invoice (draft): creation 49.11 3335 5893
invoice (draft): removal 53.15 751 6378
invoice accept: apply 43.83 17804 5259
invoice accept: cancel 47.63 744 5715
customer reserve: creation 4049.74 177 485969
customer reserve: removal 4728.84 52 567461
realization accept: apply 89.35 173 10722
realization accept: cancel 89.98 99 10798
payment from customer: creation 63.11 111 7573
payment from customer: removal 6.14 88 737
payment to supplier: creation 52.38 127 6286
payment to supplier: removal 3.51 94 421
service: total inventory turnovers 4.96 1103 595
service: total monetary turnovers 6.94 46 833
service: aggregate perf. data 0.80 19051 96
service: refresh index statistics 0.03 259341 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-08-2021 19:38:29 to 24-08-2021 19:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2577
TEST_TIME 2 5499
TEST_TIME 3 4646
TEST_TIME 4 4750
TEST_TIME 5 4457
TEST_TIME 6 5188
TEST_TIME 7 5485
TEST_TIME 8 5934
TEST_TIME 9 6310
TEST_TIME 10 6312
TEST_TIME 11 6545
TEST_TIME 12 6525
TEST_TIME 13 7028
TEST_TIME 14 7056
TEST_TIME 15 7571
TEST_TIME 16 7796
TEST_TIME 17 8268
TEST_TIME 18 8579
TEST_TIME 19 8529
TEST_TIME 20 8672
TEST_TIME 21 9308
TEST_TIME 22 9337
TEST_TIME 23 9267
TEST_TIME 24 9367
TEST_TIME 25 10056
TEST_TIME 26 10198
TEST_TIME 27 10335
TEST_TIME 28 10439
TEST_TIME 29 10796
TEST_TIME 30 10594
TEST_TIME 31 10659
TEST_TIME 32 10729
TEST_TIME 33 10610
TEST_TIME 34 10510
TEST_TIME 35 10513
TEST_TIME 36 10378
TEST_TIME 37 10451
TEST_TIME 38 10619
TEST_TIME 39 10552
TEST_TIME 40 10582
TEST_TIME 41 10504
TEST_TIME 42 10499
TEST_TIME 43 10270
TEST_TIME 44 10227
TEST_TIME 45 10358
TEST_TIME 46 10334
TEST_TIME 47 10168
TEST_TIME 48 10229
TEST_TIME 49 10162
TEST_TIME 50 10143
TEST_TIME 51 10168
TEST_TIME 52 10199
TEST_TIME 53 10079
TEST_TIME 54 10137
TEST_TIME 55 10021
TEST_TIME 56 9891
TEST_TIME 57 9959
TEST_TIME 58 9893
TEST_TIME 59 9835
TEST_TIME 60 9887
TEST_TIME 61 9743
TEST_TIME 62 9700
TEST_TIME 63 9727
TEST_TIME 64 9774
TEST_TIME 65 9819
TEST_TIME 66 9847
TEST_TIME 67 9846
TEST_TIME 68 9810
TEST_TIME 69 9900
TEST_TIME 70 9824
TEST_TIME 71 9818
TEST_TIME 72 9927
TEST_TIME 73 9894
TEST_TIME 74 9899
TEST_TIME 75 9906
TEST_TIME 76 9972
TEST_TIME 77 9869
TEST_TIME 78 9831
TEST_TIME 79 9745
TEST_TIME 80 9709
TEST_TIME 81 9690
TEST_TIME 82 9608
TEST_TIME 83 9653
TEST_TIME 84 9678
TEST_TIME 85 9632
TEST_TIME 86 9646
TEST_TIME 87 9611
TEST_TIME 88 9672
TEST_TIME 89 9661
TEST_TIME 90 9714
TEST_TIME 91 9716
TEST_TIME 92 9741
TEST_TIME 93 9713
TEST_TIME 94 9728
TEST_TIME 95 9794
TEST_TIME 96 9811
TEST_TIME 97 9783
TEST_TIME 98 9848
TEST_TIME 99 9850
TEST_TIME 100 9780
TEST_TIME 101 9715
TEST_TIME 102 9740
TEST_TIME 103 9706
TEST_TIME 104 9699
TEST_TIME 105 9660
TEST_TIME 106 9651
TEST_TIME 107 9611
TEST_TIME 108 9556
TEST_TIME 109 9529
TEST_TIME 110 9548
TEST_TIME 111 9526
TEST_TIME 112 9492
TEST_TIME 113 9494
TEST_TIME 114 9485
TEST_TIME 115 9472
TEST_TIME 116 9537
TEST_TIME 117 9502
TEST_TIME 118 9504
TEST_TIME 119 9563
TEST_TIME 120 9542

Performance per minute, chart


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

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 1594113 1594113 0 0.00 0 22308 6 0 0
doc_list_biud 1594111 1594111 0 0.00 0 51813 24 0 0
sp_add_invoice_to_stock 5394 5259 135 2.50 16 151689 17804 0 135
sp_cancel_adding_invoice 5715 5715 0 0.00 5 78292 745 0 0
sp_cancel_client_order 1959 1959 0 0.00 4 8584 318 0 0
sp_cancel_customer_reserve 567461 567461 0 0.00 1 51818 52 0 0
sp_cancel_pay_from_customer 737 737 0 0.00 3 8651 89 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 2 6533 95 0 0
sp_cancel_supplier_invoice 6385 6378 7 0.10 2 33379 751 0 7
sp_cancel_supplier_order 441 441 0 0.00 6 949540 85891 0 0
sp_cancel_write_off 10798 10798 0 0.00 1 11517 99 0 0
sp_client_order 21669 21669 0 0.00 4 17701 312 0 0
sp_customer_reserve 485969 485969 0 0.00 0 102748 178 0 0
sp_fill_shopping_cart 33405 33405 0 0.00 1 13886 39 0 0
sp_fill_shopping_cart_clo_res 485961 485961 0 0.00 0 17485 12 0 0
sp_get_clo_for_invoice 5251 5251 0 0.00 0 34302 281 0 0
sp_kill_cost_storno 17671 17671 0 0.00 0 5844 49 0 0
sp_kill_qstorno_ret_qs2qd 1152539 1152539 0 0.00 0 51805 28 0 0
sp_kill_qty_storno 608724 608724 0 0.00 0 51813 62 0 0
sp_lock_selected_doc 22187 22187 0 0.00 0 8987 5 0 0
sp_make_cost_storno 29832 29832 0 0.00 0 14972 52 0 0
sp_multiply_rows_for_pdistr 29831 29831 0 0.00 0 12723 28 0 0
sp_multiply_rows_for_qdistr 21669 21669 0 0.00 0 17691 137 0 0
sp_pay_from_customer 7573 7573 0 0.00 4 20574 111 0 0
sp_pay_to_supplier 6286 6286 0 0.00 5 16508 127 0 0
sp_payment_common 13858 13858 0 0.00 2 16497 30 0 0
sp_qd_handle_on_cancel_clo 1959 1959 0 0.00 0 8115 292 0 0
sp_qd_handle_on_invoice_upd_sts 10966 10966 0 0.00 0 20960 299 0 0
sp_qd_handle_on_reserve_upd_sts 10721 10721 0 0.00 0 14363 58 0 0
sp_reserve_write_off 10722 10722 0 0.00 6 14380 174 0 0
sp_supplier_invoice 5893 5893 0 0.00 6 98771 3336 0 0
sp_supplier_order 5849 5849 0 0.00 6 86258 1282 0 0
srv_aggregate_perf_data 96 96 0 0.00 2889 213073 19052 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1788 131333 44704 0 0
srv_find_qd_qs_mism 1093916 1093916 0 0.00 0 22306 5 0 0
srv_make_invnt_saldo 595 595 0 0.00 37 65642 1104 0 0
srv_make_money_saldo 833 833 0 0.00 1 5619 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 81730 412042 259341 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5259 5259 0 0.00 0 18875 182 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 28267 177 0 0
t$perf-norm:sp_cancel_client_order 1959 1959 0 0.00 0 4704 10 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 2373 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 737 737 0 0.00 0 890 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 3188 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 408 10 0 0
t$perf-norm:sp_cancel_supplier_order 441 441 0 0.00 0 28646 1525 0 0
t$perf-norm:sp_cancel_write_off 1088 1088 0 0.00 0 472 4 0 0
t$perf-norm:sp_client_order 21669 21669 0 0.00 0 18282 17 0 0
t$perf-norm:sp_customer_reserve 1108 1108 0 0.00 0 2394 12 0 0
t$perf-norm:sp_pay_from_customer 7573 7573 0 0.00 0 9051 11 0 0
t$perf-norm:sp_pay_to_supplier 6286 6286 0 0.00 0 4941 11 0 0
t$perf-norm:sp_reserve_write_off 10722 10722 0 0.00 0 17483 11 0 0
t$perf-norm:sp_supplier_invoice 5893 5893 0 0.00 0 8369 26 0 0
t$perf-norm:sp_supplier_order 5849 5849 0 0.00 0 17980 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 60 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 70 2 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 2870 24 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 5074 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10772 10502 270 2.50 0 6582 19 0 270
v_all_customers 39378 39378 0 0.00 0 24 1 0 0
v_all_suppliers 32616 32616 0 0.00 0 26 1 0 0
v_all_wares 43336 43336 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 434 6 0 0
v_cancel_client_order 3918 3918 0 0.00 0 1868 4 0 0
v_cancel_customer_prepayment 1474 1474 0 0.00 0 8639 10 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 1141 5 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 1779 8 0 0
v_cancel_supplier_invoice 908 894 14 1.54 0 734 14 0 14
v_cancel_supplier_order 882 882 0 0.00 0 773 3 0 0
v_cancel_write_off 2176 2176 0 0.00 0 4047 16 0 0
v_max_id_clo_ord 5844 5844 0 0.00 0 98 1 0 0
v_max_id_clo_res 1108 1108 0 0.00 0 1553 8 0 0
v_max_id_ord_sup 5890 5890 0 0.00 0 5197 3 0 0
v_max_non_paid_invoice 1711 1711 0 0.00 0 445 3 0 0
v_max_non_paid_realizn 4175 4175 0 0.00 0 6793 6 0 0
v_min_id_clo_ord 5844 5844 0 0.00 0 8208 9 0 0
v_min_id_clo_res 1108 1108 0 0.00 0 3467 9 0 0
v_min_id_ord_sup 5890 5890 0 0.00 0 9851 10 0 0
v_min_non_paid_invoice 6286 6286 0 0.00 0 6187 19 0 0
v_min_non_paid_realizn 7572 7572 0 0.00 0 10122 15 0 0
v_our_firm 10752 10752 0 0.00 0 19 1 0 0
v_reserve_write_off 21440 21440 0 0.00 0 9946 6 0 0
x_lock_depdocs_on_canc_invoice 11646 11646 0 0.00 0 15697 120 0 0
x_lock_depdocs_on_canc_sup_ord 441 441 0 0.00 0 11722 371 0 0
x_make_qstorno_1000_1200 5849 5849 0 0.00 1 85922 1193 0 0
x_make_qstorno_1200_2000 5893 5893 0 0.00 4 92746 3207 0 0
x_make_qstorno_2100_3300 485969 485969 0 0.00 0 102746 163 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-08-2021 19:38:37 to 24-08-2021 19:38:42.

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:46 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:48 32318 8192000000 10375986336 10699513856 1428929136 14.852 100 88 176 1 1464154192 6460944 28764112
17:10:24 22904 8192000000 10460152704 10796007424 1514869072 15.606 100 89 178 0 1550553936 8027568 27657296
17:11:50 13226 8192000000 10499902096 10844180480 1559219568 15.989 100 81 162 0 1594727920 9924224 25584128
17:13:06 12100 8192000000 10580465856 10940530688 1639534368 16.676 100 79 157 0 1673582272 10829408 23218496
17:14:24 8294 8192000000 10650459776 11021926400 1712868960 17.293 100 76 153 1 1747819776 12752336 22198480
17:15:37 4421 8192000000 10704081216 11082301440 1770790288 17.774 100 74 145 2 1802799392 11517056 20492048
17:16:43 10127 8192000000 10762240976 11147005952 1823476304 18.206 100 81 162 0 1859531328 13732944 22322080
17:17:56 3249 8192000000 10793990672 11179896832 1857460112 18.483 100 92 184 0 1897960352 15301392 25198848
17:19:01 1776 8192000000 10819737248 11207553024 1881089792 18.674 100 98 196 0 1923094656 14305168 27699696
17:20:04 5733 8192000000 10807857312 11218403328 1880369184 18.668 100 55 111 1 1903751600 8306480 15075936
17:21:11 3320 8192000000 10801168160 11227262976 1888188272 18.731 100 31 62 2 1904950000 7692576 9069152
17:22:17 28493 8192000000 10852259776 11251036160 1904408208 18.862 100 66 130 2 1930050880 5329440 20313232
17:23:46 4963 8192000000 10828533488 11254857728 1907902112 18.890 100 32 63 1 1924531616 7611504 9018000
17:24:54 11213 8192000000 10827536880 11261669376 1901184480 18.836 99 21 42 3 1912552144 5765280 5602384
17:26:13 9618 8192000000 10860222176 11271380992 1922109120 19.004 100 70 141 3 1951112976 7928336 21075520
17:27:23 6006 8192000000 10836811968 11266666496 1919224528 18.981 100 28 56 2 1933304944 6213584 7866832
17:28:31 7533 8192000000 10744163392 11175768064 1815580768 18.142 98 25 50 2 1829606272 5456048 8569456
17:29:42 14980 8192000000 10727967728 11153018880 1761552688 17.697 95 43 87 3 1780127312 5268656 13305968
17:30:58 8668 8192000000 10675422464 11093913600 1741086976 17.528 96 16 32 1 1750616064 5087600 4441488
17:32:07 4097 8192000000 10709447328 11131334656 1800542496 18.018 100 37 74 1 1817623952 6862208 10219248
17:33:17 7683 8192000000 10763336368 11178496000 1834573824 18.297 100 30 61 2 1852703888 9125920 9004144
17:34:26 18160 8192000000 10781635600 11209146368 1864363008 18.539 100 28 56 0 1878753248 5640016 8750224
17:35:46 14151 8192000000 10784394208 11217756160 1873695808 18.614 100 31 62 1 1889756704 7646048 8414848
17:37:03 9125 8192000000 10808996592 11236114432 1889095968 18.738 100 31 62 3 1902626112 4855744 8674400
17:38:16 11814 8192000000 10866189152 11280953344 1918110064 18.972 100 47 91 2 1939658960 9110704 12438192
17:39:36 3523 8192000000 10896404432 11307966464 1947159760 19.204 100 71 141 0 1981556048 13445680 20950608
17:40:40 41814 8192000000 10872494384 11314409472 1947990608 19.210 100 33 65 1 1970097024 12446128 9660288
17:42:32 33091 8192000000 10909703024 11344998400 1968754768 19.376 100 44 88 1 2001937536 20379392 12803376
17:44:09 12838 8192000000 10919932176 11348582400 1959906288 19.305 99 56 112 1 2000176768 24140400 16130080
17:45:35 1787 8192000000 10939372144 11350114304 1974076176 19.418 99 83 166 1 2023426704 25544720 23805808
17:46:40 2557 8192000000 10955308176 11380338688 1998340080 19.610 100 66 132 0 2044635488 26244528 20050880
17:47:46 9459 8192000000 10965648272 11390779392 2008271984 19.688 100 75 151 0 2059640656 28895712 22472960
17:49:05 5513 8192000000 10956577344 11380772864 1991453072 19.555 99 68 136 1 2045337808 33110944 20773792
17:50:16 3612 8192000000 10962261760 11381129216 1981096736 19.473 100 68 134 1 2044013776 42178224 20738816
17:51:24 3984 8192000000 10957751536 11375976448 1982294064 19.483 100 80 161 1 2043544144 37239056 24011024
17:52:33 6306 8192000000 10920311152 11338436608 1932670528 19.088 99 64 128 2 1987033200 34988256 19374416
17:53:43 12271 8192000000 10881684704 11318042624 1898607760 18.815 98 62 116 2 1951599632 35136512 17855360
17:55:00 2178 8192000000 10916889328 11320373248 1936754976 19.121 100 86 169 2 1994140112 32083216 25301920
17:56:04 14587 8192000000 10907902176 11333656576 1939151888 19.140 100 49 99 1 1990036224 35235488 15648848
17:57:27 1891 8192000000 10947105472 11358932992 1959338400 19.301 100 86 172 0 2032926784 46328560 27259824
17:58:34 18107 8192000000 10919324672 11359641600 1963391664 19.333 100 32 64 2 2003193968 28998688 10803616
17:59:57 11432 8192000000 10929847888 11373985792 1975345840 19.428 100 28 56 0 2018048512 32902944 9799728
18:01:14 9737 8192000000 10938186656 11374395392 1969483648 19.381 100 44 86 0 2023881552 39643936 14753968
18:02:27 12326 8192000000 10898780288 11355201536 1946896752 19.202 100 31 62 0 1992525280 35493328 10135200
18:03:45 42226 8192000000 10880091104 11345547264 1933443216 19.094 99 30 60 1 1969171456 26200976 9527264
18:05:34 39585 8192000000 10876953328 11337232384 1931357872 19.078 100 16 32 0 1960065904 23340848 5367184
18:07:14 80802 8192000000 10879952128 11339026432 1898352432 18.813 97 15 28 1 1914707440 13250320 3104688
18:09:47 102171 8192000000 10897572320 11363037184 1965146640 19.347 100 9 18 0 1983382880 15449568 2786672
18:12:30 103284 8192000000 10925904864 11388739584 1981120512 19.474 100 12 24 0 1997146064 12817248 3208304
18:15:14 116878 8192000000 10937019712 11383525376 1973031312 19.409 99 18 36 2 1999870448 21118096 5721040
18:18:15 50212 8192000000 10950407248 11416424448 1997238320 19.601 100 14 28 0 2025550864 23906080 4406464
18:20:08 90088 8192000000 10927827984 11402731520 1997374048 19.602 100 33 64 1 2020213280 13135696 9703536
18:22:39 124872 8192000000 10915563968 11373658112 1920329296 18.989 96 31 61 0 1940167424 10755392 9082736
18:25:45 45330 8192000000 10910261184 11383132160 1967851984 19.368 99 12 24 0 1981850368 10142832 3855552
18:27:32 29082 8192000000 10933754864 11400261632 1990353872 19.547 100 41 82 0 2017613360 15058752 12200736
18:29:02 33861 8192000000 10900667504 11394469888 1936831264 19.121 98 6 12 0 1958859792 20512448 1516080
18:31:00 21583 8192000000 10899572848 11373842432 1944435872 19.182 98 10 20 1 1963358160 15723520 3198768
18:32:25 30247 8192000000 10894879632 11371524096 1957603952 19.287 99 14 28 2 1972128064 10633696 3890416
18:33:55 55676 8192000000 10893278208 11392241664 1962571792 19.326 99 15 30 0 1978909312 12565856 3771664
18:35:54 28408 8192000000 10890302544 11327447040 1925875520 19.034 97 41 82 1 1945243104 7793360 11574224
18:37:31 61989 8192000000 10879513376 11375890432 1948765840 19.217 99 6 12 0 1962274928 11582592 1926496
18:39:33 74890 8192000000 10874276096 11362316288 1891321152 18.756 96 8 16 0 1907756848 13848192 2587504
18:41:54 70881 8192000000 10843389792 11320459264 1823691440 18.208 93 15 30 2 1835218000 7583760 3942800
18:44:05 16432 8192000000 10864428352 11317673984 1905184128 18.868 98 44 87 2 1927413184 10046800 12182256
18:45:22 8022 8192000000 10898072272 11353690112 1943686880 19.176 99 50 100 2 1968394320 10511248 14196192
18:46:33 38203 8192000000 10846029888 11319672832 1836245536 18.310 95 22 44 2 1866769184 24374864 6148784
18:48:12 51135 8192000000 10884072256 11350728704 1931759680 19.081 99 34 68 1 1958236816 16431584 10045552
18:50:20 11857 8192000000 10887289840 11338833920 1933671952 19.096 99 39 78 1 1953232816 7321296 12239568
18:51:36 35458 8192000000 10883885360 11332235264 1893507344 18.774 97 31 61 0 1910797344 8712752 8577248
18:53:15 69987 8192000000 10884905776 11347222528 1914609920 18.944 98 11 22 1 1934440304 16668528 3161856
18:55:28 55408 8192000000 10876414464 11330326528 1919655072 18.984 98 42 80 3 1938500400 7384496 11460832
18:57:39 35533 8192000000 10885130272 11359629312 1961894800 19.321 100 33 66 0 1988248624 17042400 9311424
19:01:28 39892 8192000000 10877550736 11367854080 1947202384 19.204 99 19 38 0 1967769184 14376848 6189952
19:03:12 52475 8192000000 10887358960 11373170688 1946591840 19.199 99 10 21 1 1964547024 15034432 2920752
19:06:03 131332 8192000000 10915663680 11388129280 1935613680 19.112 97 15 28 2 1953557264 14151072 3792512
19:09:19 63218 8192000000 10925235424 11392233472 1977168272 19.442 99 21 41 2 1990258800 7433392 5657136
19:11:23 62633 8192000000 10924001136 11414634496 1958994672 19.298 98 10 20 0 1977930112 16645888 2289552
19:13:26 61344 8192000000 10945017776 11396358144 1963121872 19.331 98 31 62 1 1982257280 9836400 9299008
19:15:29 119175 8192000000 10905065440 11373318144 1856407168 18.474 93 20 40 2 1878001152 16747776 4846208
19:18:32 88607 8192000000 10906713824 11389538304 1952687280 19.248 99 17 34 0 1975936144 18256032 4992832
19:21:01 56609 8192000000 10890731024 11378892800 1976824432 19.440 100 11 22 0 1989333344 8955232 3553680
19:23:03 58389 8192000000 10872322208 11354501120 1899814960 18.825 96 16 32 0 1911132608 6859184 4458464
19:25:02 81619 8192000000 10855433696 11334270976 1885216144 18.707 96 15 29 1 1897880560 8548160 4116256
19:27:24 74337 8192000000 10860090496 11344637952 1907227376 18.884 98 10 20 0 1917975344 8287936 2460032
19:29:38 58740 8192000000 10868898688 11330068480 1885216864 18.707 97 25 50 2 1902372192 10247968 6907360
19:31:38 42959 8192000000 10887611424 11360202752 1938455648 19.134 99 29 58 1 1959514896 12963328 8095920
19:33:22 51045 8192000000 10886550800 11364245504 1903912240 18.858 97 14 28 0 1918304176 10811648 3580288
19:35:15 75674 8192000000 10869885728 11360628736 1863401600 18.531 95 25 50 1 1888288640 17851168 7035872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-08-2021 19:38:43 to 24-08-2021 19:38:58.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-08-2021 19:38:59 to 24-08-2021 19:39:01.

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          247932
MON$OLDEST_ACTIVE               248904
MON$OLDEST_SNAPSHOT             248904
MON$NEXT_TRANSACTION            248904
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                       2048352
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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'

24.08.21 19:39:02. 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-24 19:39:05 No severe PSQL-related problems occured
24.08.21 19:39:06. End of report.