Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.08.2021 16: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 16124304 kB
MemAvailable 30988792 kB
Buffers 0 kB
Cached 15580192 kB
SwapCached 3640 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-21 16:37:45
DTS_END                         2021-08-21 16:37:45.6260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41498
TRN_ID                          245446
ATT_ID                          723
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: 9648.21 17123 1157785
customer order: creation 182.82 337 21938
customer order: refuse 15.60 371 1872
order to supplier: creation 48.10 1177 5772
order to supplier: removal 3.54 96510 425
invoice (draft): creation 48.32 3203 5798
invoice (draft): removal 54.20 717 6504
invoice accept: apply 43.17 17600 5180
invoice accept: cancel 49.97 842 5996
customer reserve: creation 4007.20 174 480864
customer reserve: removal 4880.01 56 585601
realization accept: apply 88.34 186 10601
realization accept: cancel 90.47 99 10856
payment from customer: creation 62.46 138 7495
payment from customer: removal 6.05 84 726
payment to supplier: creation 51.67 135 6200
payment to supplier: removal 3.63 78 436
service: total inventory turnovers 4.62 1260 554
service: total monetary turnovers 7.23 31 867
service: aggregate perf. data 0.80 22246 96
service: refresh index statistics 0.03 197221 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-08-2021 16:39:18 to 21-08-2021 16:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3907
TEST_TIME 2 3459
TEST_TIME 3 3247
TEST_TIME 4 3472
TEST_TIME 5 4013
TEST_TIME 6 3930
TEST_TIME 7 4063
TEST_TIME 8 4387
TEST_TIME 9 4616
TEST_TIME 10 4800
TEST_TIME 11 5838
TEST_TIME 12 6364
TEST_TIME 13 6481
TEST_TIME 14 6465
TEST_TIME 15 6708
TEST_TIME 16 7110
TEST_TIME 17 7067
TEST_TIME 18 7443
TEST_TIME 19 7419
TEST_TIME 20 7798
TEST_TIME 21 8041
TEST_TIME 22 8741
TEST_TIME 23 8669
TEST_TIME 24 8617
TEST_TIME 25 9027
TEST_TIME 26 8812
TEST_TIME 27 9308
TEST_TIME 28 9554
TEST_TIME 29 9674
TEST_TIME 30 10871
TEST_TIME 31 10926
TEST_TIME 32 10759
TEST_TIME 33 10834
TEST_TIME 34 11045
TEST_TIME 35 10953
TEST_TIME 36 10879
TEST_TIME 37 10799
TEST_TIME 38 10912
TEST_TIME 39 10758
TEST_TIME 40 10629
TEST_TIME 41 10555
TEST_TIME 42 10559
TEST_TIME 43 10430
TEST_TIME 44 10350
TEST_TIME 45 10297
TEST_TIME 46 10150
TEST_TIME 47 10133
TEST_TIME 48 10228
TEST_TIME 49 10183
TEST_TIME 50 10197
TEST_TIME 51 10138
TEST_TIME 52 10107
TEST_TIME 53 10195
TEST_TIME 54 10192
TEST_TIME 55 10213
TEST_TIME 56 10194
TEST_TIME 57 10214
TEST_TIME 58 10232
TEST_TIME 59 10173
TEST_TIME 60 10141
TEST_TIME 61 10185
TEST_TIME 62 10343
TEST_TIME 63 10319
TEST_TIME 64 10341
TEST_TIME 65 10374
TEST_TIME 66 10287
TEST_TIME 67 10330
TEST_TIME 68 10328
TEST_TIME 69 10199
TEST_TIME 70 10152
TEST_TIME 71 10219
TEST_TIME 72 10121
TEST_TIME 73 10121
TEST_TIME 74 10075
TEST_TIME 75 10052
TEST_TIME 76 10028
TEST_TIME 77 9958
TEST_TIME 78 9906
TEST_TIME 79 9821
TEST_TIME 80 9886
TEST_TIME 81 9817
TEST_TIME 82 9822
TEST_TIME 83 9805
TEST_TIME 84 9803
TEST_TIME 85 9812
TEST_TIME 86 9849
TEST_TIME 87 9812
TEST_TIME 88 9825
TEST_TIME 89 9835
TEST_TIME 90 9855
TEST_TIME 91 9861
TEST_TIME 92 9940
TEST_TIME 93 9901
TEST_TIME 94 9942
TEST_TIME 95 10052
TEST_TIME 96 9991
TEST_TIME 97 9925
TEST_TIME 98 9850
TEST_TIME 99 9832
TEST_TIME 100 9815
TEST_TIME 101 9767
TEST_TIME 102 9748
TEST_TIME 103 9691
TEST_TIME 104 9672
TEST_TIME 105 9684
TEST_TIME 106 9680
TEST_TIME 107 9658
TEST_TIME 108 9640
TEST_TIME 109 9626
TEST_TIME 110 9628
TEST_TIME 111 9717
TEST_TIME 112 9685
TEST_TIME 113 9679
TEST_TIME 114 9664
TEST_TIME 115 9759
TEST_TIME 116 9729
TEST_TIME 117 9726
TEST_TIME 118 9774
TEST_TIME 119 9698
TEST_TIME 120 9650

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-08-2021 16:39:22 to 21-08-2021 16:39:25.

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 1602686 1602686 0 0.00 0 31452 6 0 0
doc_list_biud 1602685 1602685 0 0.00 0 82336 26 0 0
sp_add_invoice_to_stock 5368 5180 188 3.50 73 194952 17601 0 188
sp_cancel_adding_invoice 5996 5996 0 0.00 4 82968 843 0 0
sp_cancel_client_order 1872 1872 0 0.00 4 21939 372 0 0
sp_cancel_customer_reserve 585601 585601 0 0.00 1 25522 56 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 3 7770 85 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 2 9978 79 0 0
sp_cancel_supplier_invoice 6514 6504 10 0.15 1 82343 718 0 10
sp_cancel_supplier_order 425 425 0 0.00 7 1109557 96510 0 0
sp_cancel_write_off 10856 10856 0 0.00 1 11235 100 0 0
sp_client_order 21938 21938 0 0.00 4 21032 338 0 0
sp_customer_reserve 480864 480864 0 0.00 0 56009 174 0 0
sp_fill_shopping_cart 33501 33501 0 0.00 1 17314 37 0 0
sp_fill_shopping_cart_clo_res 480858 480858 0 0.00 0 17095 13 0 0
sp_get_clo_for_invoice 5173 5173 0 0.00 0 18360 265 0 0
sp_kill_cost_storno 18014 18014 0 0.00 0 10942 49 0 0
sp_kill_qstorno_ret_qs2qd 1188987 1188987 0 0.00 0 82332 30 0 0
sp_kill_qty_storno 627027 627027 0 0.00 0 82334 65 0 0
sp_lock_selected_doc 21816 21816 0 0.00 0 8759 4 0 0
sp_make_cost_storno 29469 29469 0 0.00 0 21753 59 0 0
sp_multiply_rows_for_pdistr 29468 29468 0 0.00 0 21453 29 0 0
sp_multiply_rows_for_qdistr 21938 21938 0 0.00 0 17142 154 0 0
sp_pay_from_customer 7495 7495 0 0.00 4 27316 138 0 0
sp_pay_to_supplier 6200 6200 0 0.00 5 22105 136 0 0
sp_payment_common 13695 13695 0 0.00 1 21999 42 0 0
sp_qd_handle_on_cancel_clo 1872 1872 0 0.00 0 21905 350 0 0
sp_qd_handle_on_invoice_upd_sts 11168 11168 0 0.00 0 28358 314 0 0
sp_qd_handle_on_reserve_upd_sts 10601 10601 0 0.00 0 22109 58 0 0
sp_reserve_write_off 10601 10601 0 0.00 6 31463 187 0 0
sp_supplier_invoice 5798 5798 0 0.00 7 89918 3203 0 0
sp_supplier_order 5772 5772 0 0.00 4 43409 1178 0 0
srv_aggregate_perf_data 96 96 0 0.00 3205 463522 22247 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2283 120864 44762 0 0
srv_find_qd_qs_mism 1107431 1107431 0 0.00 0 18680 5 0 0
srv_make_invnt_saldo 554 554 0 0.00 47 71457 1261 0 0
srv_make_money_saldo 867 867 0 0.00 1 4339 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 104260 278001 197221 0 0
t$perf-abend:sp_add_invoice_to_stock 188 188 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5180 5180 0 0.00 0 30087 163 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 1 2541 83 0 0
t$perf-norm:sp_cancel_client_order 1872 1872 0 0.00 0 5990 15 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 9499 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 4594 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 273 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 509 8 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 22480 1485 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 28187 37 0 0
t$perf-norm:sp_client_order 21938 21938 0 0.00 0 28639 16 0 0
t$perf-norm:sp_customer_reserve 1091 1091 0 0.00 0 835 4 0 0
t$perf-norm:sp_pay_from_customer 7495 7495 0 0.00 0 11401 8 0 0
t$perf-norm:sp_pay_to_supplier 6200 6200 0 0.00 0 7668 11 0 0
t$perf-norm:sp_reserve_write_off 10601 10601 0 0.00 0 12551 12 0 0
t$perf-norm:sp_supplier_invoice 5798 5798 0 0.00 0 6965 15 0 0
t$perf-norm:sp_supplier_order 5772 5772 0 0.00 0 2411 12 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 201 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 1829 24 0 0
t$perf-norm:srv_make_money_saldo 867 867 0 0.00 0 591 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10720 10344 376 3.50 0 10163 21 0 376
v_all_customers 40102 40102 0 0.00 0 89 1 0 0
v_all_suppliers 32094 32094 0 0.00 0 88 1 0 0
v_all_wares 43876 43876 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 5105 12 0 0
v_cancel_client_order 3744 3744 0 0.00 0 1828 4 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 3323 8 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 533 3 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 433 3 0 0
v_cancel_supplier_invoice 794 774 20 2.51 0 185 13 0 20
v_cancel_supplier_order 850 850 0 0.00 0 1053 5 0 0
v_cancel_write_off 2052 2052 0 0.00 0 10677 20 0 0
v_max_id_clo_ord 5772 5772 0 0.00 0 260 1 0 0
v_max_id_clo_res 1091 1091 0 0.00 0 1500 14 0 0
v_max_id_ord_sup 5791 5791 0 0.00 0 3467 3 0 0
v_max_non_paid_invoice 1716 1716 0 0.00 0 312 2 0 0
v_max_non_paid_realizn 3822 3822 0 0.00 0 1103 3 0 0
v_min_id_clo_ord 5772 5772 0 0.00 0 17057 14 0 0
v_min_id_clo_res 1091 1091 0 0.00 0 4577 16 0 0
v_min_id_ord_sup 5791 5791 0 0.00 0 15102 19 0 0
v_min_non_paid_invoice 6200 6200 0 0.00 0 21996 29 0 0
v_min_non_paid_realizn 7495 7495 0 0.00 0 21627 36 0 0
v_our_firm 11120 11120 0 0.00 0 15 1 0 0
v_reserve_write_off 21202 21202 0 0.00 0 7003 6 0 0
x_lock_depdocs_on_canc_invoice 12113 12113 0 0.00 0 21980 123 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 11839 341 0 0
x_make_qstorno_1000_1200 5772 5772 0 0.00 2 43400 1094 0 0
x_make_qstorno_1200_2000 5798 5798 0 0.00 3 84228 3073 0 0
x_make_qstorno_2100_3300 480863 480863 0 0.00 0 56007 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-08-2021 16:39:26 to 21-08-2021 16:39:30.

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
14:07:49 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:50 38131 8192000000 10382414032 10702696448 1437527312 14.928 100 90 180 0 1472501296 5977152 28996832
14:10:35 7248 8192000000 10445972016 10775973888 1499896816 15.475 100 91 180 1 1536924784 8189328 28838640
14:11:53 16500 8192000000 10506353168 10848829440 1559334144 15.990 100 87 173 1 1595575888 9576320 26665424
14:13:20 11091 8192000000 10566133408 10926379008 1631031248 16.604 100 80 159 1 1666764128 11515296 24217584
14:14:34 8666 8192000000 10630311872 10999308288 1686195424 17.069 100 74 148 2 1719249216 11425440 21628352
14:15:45 4346 8192000000 10689577104 11069534208 1754223760 17.637 100 72 146 1 1787105808 12575472 20306576
14:16:51 4829 8192000000 10726630320 11116060672 1793453008 17.960 100 71 140 1 1825889760 12759424 19677328
14:18:00 2221 8192000000 10791218160 11176546304 1846512224 18.394 100 86 171 0 1884891984 13310560 25069200
14:19:04 1647 8192000000 10804438784 11197730816 1860784960 18.510 100 91 184 0 1900120256 13259840 26075456
14:20:07 2453 8192000000 10811184864 11206524928 1869117312 18.577 100 73 144 1 1896689040 7334320 20237408
14:21:10 5892 8192000000 10791027200 11211378688 1876976208 18.641 100 35 69 3 1893214976 6480960 9757808
14:22:16 5474 8192000000 10805888336 11225001984 1884870016 18.704 100 28 56 0 1899487024 6127632 8489376
14:23:23 8712 8192000000 10791456912 11227492352 1889226560 18.740 100 22 44 3 1901532288 6056016 6249712
14:24:32 42631 8192000000 10803628320 11242377216 1891896576 18.761 100 26 50 1 1904643680 5066704 7680400
14:26:21 7437 8192000000 10814557088 11252166656 1899194672 18.820 100 21 42 0 1911251904 6554992 5502240
14:27:29 6857 8192000000 10806518624 11234107392 1864240960 18.538 98 27 54 0 1878339344 5834512 8263872
14:28:37 29565 8192000000 10766242592 11193552896 1810151520 18.097 96 25 52 1 1823619072 6144000 7323552
14:30:23 4563 8192000000 10754220544 11166576640 1819757872 18.176 99 38 74 3 1838424112 8012912 10653328
14:31:29 8692 8192000000 10685229840 11102515200 1717239952 17.329 95 29 58 0 1731415040 5602432 8572656
14:32:37 6017 8192000000 10737538880 11142586368 1787511296 17.911 99 42 85 2 1811405872 10210928 13683648
14:33:46 64883 8192000000 10754722288 11184787456 1841406544 18.352 100 21 42 3 1852975648 5381968 6187136
14:35:54 6374 8192000000 10785635488 11214618624 1867894560 18.567 100 29 58 0 1883422160 7237008 8290592
14:37:01 8145 8192000000 10807311232 11236519936 1888209376 18.731 100 27 53 1 1905926576 9198624 8518576
14:38:14 10797 8192000000 10839550896 11260715008 1913855632 18.938 100 40 78 1 1931957984 7308096 10794256
14:39:29 62565 8192000000 10876149184 11299635200 1934349616 19.102 100 49 97 1 1959666960 11262080 14055264
14:41:51 15432 8192000000 10890861776 11323564032 1954743312 19.264 100 46 92 1 1983803200 16333248 12726640
14:43:11 31292 8192000000 10905984512 11341893632 1972020688 19.401 100 50 100 3 2002007744 15688496 14298560
14:44:46 7248 8192000000 10934499728 11362942976 1988875312 19.535 100 50 100 1 2021218256 17658032 14684912
14:45:58 6177 8192000000 10950085168 11375882240 1981922512 19.480 99 60 120 2 2019322512 20379184 17020816
14:47:14 10123 8192000000 10957890304 11383406592 1999580800 19.619 100 62 123 0 2045352288 26561536 19209952
14:48:28 5093 8192000000 10967670176 11403071488 2006728752 19.676 100 57 114 0 2054373616 28908256 18736608
14:49:39 6622 8192000000 10978013904 11408052224 2014305744 19.735 100 54 108 2 2060021536 28169648 17546144
14:50:51 9593 8192000000 10985340256 11425918976 2025767232 19.825 100 45 89 2 2067744816 28311488 13666096
14:52:08 4933 8192000000 10968473600 11400589312 1998501984 19.611 100 63 126 2 2051584304 33929200 19153120
14:53:24 2281 8192000000 10968915056 11386859520 1960051712 19.306 98 80 159 3 2023046624 39152672 23842240
14:54:28 8859 8192000000 10926545008 11370233856 1950345136 19.229 99 30 60 2 2000387616 40165696 9876784
14:55:44 2317 8192000000 10967601280 11377278976 1971218224 19.395 100 79 157 2 2041924896 46278704 24427968
14:56:56 2747 8192000000 10946091696 11348922368 1915200912 18.948 98 80 157 4 1992546800 53945664 23400224
14:58:01 12895 8192000000 10941478768 11374166016 1953327648 19.253 100 38 76 2 2022142336 55709664 13105024
14:59:30 15202 8192000000 10942294864 11399413760 1965789040 19.352 100 27 54 2 2028949440 53960432 9199968
15:01:00 25405 8192000000 10927029472 11373551616 1953322528 19.253 100 29 58 3 2014137936 50985584 9829824
15:02:46 14569 8192000000 10906255648 11341766656 1930014560 19.067 100 25 49 3 1991718944 53613760 8090624
15:04:03 14095 8192000000 10946548800 11380830208 1948304960 19.213 100 37 73 0 2023449456 63063824 12080672
15:05:25 11598 8192000000 10921362016 11371200512 1940589088 19.151 100 30 60 0 2006939056 56202832 10147136
15:06:53 56704 8192000000 10877306480 11349929984 1916342560 18.958 99 21 42 4 1986430512 63214480 6873472
15:08:49 53702 8192000000 10888141312 11330965504 1900401552 18.830 97 25 50 0 1920478496 13493248 6583696
15:10:44 83121 8192000000 10876654960 11350847488 1891225392 18.756 97 3 6 0 1909987680 18164736 597552
15:13:19 80076 8192000000 10915678384 11366195200 1933448448 19.094 98 36 70 2 1964593312 21578320 9566544
15:16:08 68152 8192000000 10893498464 11353153536 1948619776 19.215 99 17 32 0 1961992432 8550432 4822224
15:18:41 45782 8192000000 10908559984 11370487808 1972847808 19.408 100 25 50 2 1991361040 11495648 7017584
15:20:35 51057 8192000000 10912925344 11379462144 1984982848 19.504 100 19 38 1 1997948160 7736608 5228704
15:22:27 28553 8192000000 10941881408 11414581248 2014016544 19.733 100 31 61 5 2037834928 14631376 9187008
15:23:56 20591 8192000000 10955885360 11410165760 1980250976 19.467 98 50 100 3 2013824624 18921808 14651840
15:25:18 35771 8192000000 10932063872 11428024320 1999462704 19.618 100 9 18 0 2025886048 23395600 3027744
15:26:55 46530 8192000000 10934755984 11417055232 2003472080 19.650 100 8 16 0 2025248240 19405168 2370992
15:28:46 69815 8192000000 10920225184 11396571136 1979646208 19.462 99 25 50 0 2004390608 17477120 7267280
15:31:11 30549 8192000000 10908488736 11381080064 1954399760 19.262 98 15 29 2 1969804400 11308816 4095824
15:32:44 47063 8192000000 10929893616 11402850304 1999626512 19.620 100 18 36 2 2016082432 10020192 6435728
15:34:40 59743 8192000000 10918006656 11389890560 1957642208 19.287 98 22 42 1 1972741952 8650256 6449488
15:36:41 70781 8192000000 10931446992 11393589248 1914775856 18.945 96 18 36 1 1933926704 14161056 4989792
15:38:53 87066 8192000000 10903403488 11384446976 1908358704 18.893 97 11 22 0 1933186928 22035744 2792480
15:41:20 120863 8192000000 10844385040 11312058368 1715571120 17.315 88 8 16 0 1730307120 12267664 2468336
15:44:21 41080 8192000000 10867343776 11323932672 1894315344 18.781 98 40 81 0 1918791456 13272272 11203840
15:46:08 115717 8192000000 10853702480 11317411840 1889779984 18.744 98 29 58 1 1910343280 11325616 9237680
15:49:04 17774 8192000000 10861085408 11316961280 1928139696 19.052 100 32 63 1 1945849504 9222032 8487776
15:50:23 41578 8192000000 10873043632 11352875008 1952721056 19.248 100 29 58 1 1975122704 14060192 8341456
15:52:09 67440 8192000000 10852229728 11313168384 1828302752 18.245 94 15 30 2 1848929856 16013552 4613552
15:54:18 83940 8192000000 10851188880 11320999936 1914219328 18.941 99 13 26 0 1925592752 7694960 3678464
15:56:53 60214 8192000000 10860239584 11342770176 1924199504 19.020 99 12 24 0 1943086752 15074000 3813248
15:58:57 90321 8192000000 10858346624 11339399168 1856556976 18.475 95 11 23 1 1869978928 10038160 3383792
16:01:28 65896 8192000000 10861452304 11343192064 1891271792 18.756 97 20 40 0 1906017264 9134592 5610880
16:03:34 36284 8192000000 10866706064 11342639104 1930603904 19.072 99 24 48 1 1947089600 8697056 7788640
16:05:12 38993 8192000000 10889406688 11353767936 1924269248 19.021 98 30 60 1 1941842032 9455776 8117008
16:06:53 41130 8192000000 10896805888 11365376000 1965912208 19.353 100 17 34 0 1981614400 11076528 4625664
16:08:40 107384 8192000000 10904100912 11379220480 1940012448 19.147 98 12 24 0 1965460288 22196112 3251728
16:11:28 99687 8192000000 10904843776 11394076672 1947205696 19.204 98 17 34 1 1968966896 16939408 4821792
16:14:08 95137 8192000000 10890799568 11365281792 1949900864 19.226 99 22 44 0 1975237312 19127664 6208784
16:16:51 29057 8192000000 10895686784 11360657408 1940637376 19.152 98 32 65 0 1956969072 7403264 8928432
16:18:22 61379 8192000000 10869568768 11353993216 1939260928 19.141 99 9 18 2 1950997904 8950256 2786720
16:20:31 55723 8192000000 10846717760 11324248064 1887837632 18.728 96 23 46 2 1906500944 11505920 7157392
16:22:28 64474 8192000000 10844103552 11323248640 1889118176 18.739 97 22 43 1 1905921872 10931728 5871968
16:24:33 52631 8192000000 10866110432 11329536000 1929174064 19.060 99 18 36 0 1945471056 11085504 5211488
16:26:27 62056 8192000000 10862368112 11334418432 1856519632 18.475 95 15 30 0 1876583488 15503600 4560256
16:29:07 26448 8192000000 10858171856 11317501952 1882651520 18.687 97 36 72 0 1899993488 7362560 9979408
16:30:40 49827 8192000000 10899380912 11358007296 1908180384 18.892 98 27 54 0 1928931744 13339296 7412064
16:32:36 70870 8192000000 10884847616 11357401088 1952502688 19.246 100 14 28 0 1976158560 20161168 3494704
16:35:16 50717 8192000000 10859451440 11332919296 1901183344 18.836 98 15 30 0 1920181296 14976896 4021056
16:37:07 47976 8192000000 9117221200 9354711040 283005936 3.339 15 1 2 0 285281552 2213488 62128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-08-2021 16:39:32 to 21-08-2021 16:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 188 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-08-2021 16:39:48 to 21-08-2021 16:39:50.

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          246614
MON$OLDEST_ACTIVE               246615
MON$OLDEST_SNAPSHOT             246615
MON$NEXT_TRANSACTION            246615
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                       2046816
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 ---
:230: 
:231: mscs-test-161.budzdorov.ru    Sat Aug 21 16:37:57 2021
:232:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:233: 
--- end of diff output ---

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

21.08.21 16:39:51. 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-21 16:39:53 No severe PSQL-related problems occured
21.08.21 16:39:54. End of report.