Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.2021 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16832160 kB
MemAvailable 31451392 kB
Buffers 0 kB
Cached 14872636 kB
SwapCached 0 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.9
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-12-16 19:37:56
DTS_END                         2021-12-16 19:37:56.4810
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/53142
TRN_ID                          241891
ATT_ID                          631
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: 8691.67 18293 1043001
customer order: creation 177.17 333 21260
customer order: refuse 15.36 383 1843
order to supplier: creation 48.21 1186 5785
order to supplier: removal 3.48 89747 417
invoice (draft): creation 46.60 3195 5592
invoice (draft): removal 47.45 792 5694
invoice accept: apply 41.73 18331 5008
invoice accept: cancel 42.47 832 5096
customer reserve: creation 3824.53 182 458944
customer reserve: removal 4154.09 60 498491
realization accept: apply 86.19 196 10343
realization accept: cancel 71.81 114 8617
payment from customer: creation 60.76 124 7291
payment from customer: removal 5.57 90 668
payment to supplier: creation 50.73 149 6088
payment to supplier: removal 3.33 79 399
service: total inventory turnovers 4.62 1487 554
service: total monetary turnovers 6.58 27 790
service: aggregate perf. data 0.98 16634 117
service: refresh index statistics 0.03 231923 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2021 19:39:29 to 16-12-2021 19:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3201
TEST_TIME 2 3702
TEST_TIME 3 3550
TEST_TIME 4 3799
TEST_TIME 5 4208
TEST_TIME 6 3904
TEST_TIME 7 4589
TEST_TIME 8 4720
TEST_TIME 9 5119
TEST_TIME 10 5274
TEST_TIME 11 6102
TEST_TIME 12 6171
TEST_TIME 13 6660
TEST_TIME 14 6968
TEST_TIME 15 7150
TEST_TIME 16 7402
TEST_TIME 17 7450
TEST_TIME 18 8216
TEST_TIME 19 8310
TEST_TIME 20 8354
TEST_TIME 21 8507
TEST_TIME 22 8544
TEST_TIME 23 9129
TEST_TIME 24 9116
TEST_TIME 25 9421
TEST_TIME 26 9900
TEST_TIME 27 9849
TEST_TIME 28 9833
TEST_TIME 29 9662
TEST_TIME 30 10053
TEST_TIME 31 9825
TEST_TIME 32 9800
TEST_TIME 33 9645
TEST_TIME 34 9556
TEST_TIME 35 9389
TEST_TIME 36 9335
TEST_TIME 37 9401
TEST_TIME 38 9297
TEST_TIME 39 9142
TEST_TIME 40 9056
TEST_TIME 41 9000
TEST_TIME 42 8943
TEST_TIME 43 8900
TEST_TIME 44 9040
TEST_TIME 45 8922
TEST_TIME 46 8860
TEST_TIME 47 8762
TEST_TIME 48 8945
TEST_TIME 49 8872
TEST_TIME 50 8960
TEST_TIME 51 8944
TEST_TIME 52 8926
TEST_TIME 53 8919
TEST_TIME 54 8913
TEST_TIME 55 8948
TEST_TIME 56 8897
TEST_TIME 57 8987
TEST_TIME 58 8978
TEST_TIME 59 9002
TEST_TIME 60 9015
TEST_TIME 61 9044
TEST_TIME 62 8990
TEST_TIME 63 8873
TEST_TIME 64 8865
TEST_TIME 65 8811
TEST_TIME 66 8826
TEST_TIME 67 8758
TEST_TIME 68 8822
TEST_TIME 69 8787
TEST_TIME 70 8695
TEST_TIME 71 8715
TEST_TIME 72 8735
TEST_TIME 73 8692
TEST_TIME 74 8703
TEST_TIME 75 8637
TEST_TIME 76 8614
TEST_TIME 77 8852
TEST_TIME 78 8885
TEST_TIME 79 8891
TEST_TIME 80 8826
TEST_TIME 81 8872
TEST_TIME 82 8859
TEST_TIME 83 8853
TEST_TIME 84 8816
TEST_TIME 85 8948
TEST_TIME 86 8857
TEST_TIME 87 8842
TEST_TIME 88 8850
TEST_TIME 89 8800
TEST_TIME 90 8804
TEST_TIME 91 8797
TEST_TIME 92 8738
TEST_TIME 93 8723
TEST_TIME 94 8698
TEST_TIME 95 8752
TEST_TIME 96 8735
TEST_TIME 97 8728
TEST_TIME 98 8765
TEST_TIME 99 8806
TEST_TIME 100 8786
TEST_TIME 101 8814
TEST_TIME 102 8879
TEST_TIME 103 8865
TEST_TIME 104 8868
TEST_TIME 105 8836
TEST_TIME 106 8898
TEST_TIME 107 8900
TEST_TIME 108 8924
TEST_TIME 109 8930
TEST_TIME 110 8877
TEST_TIME 111 8847
TEST_TIME 112 8813
TEST_TIME 113 8809
TEST_TIME 114 8803
TEST_TIME 115 8785
TEST_TIME 116 8756
TEST_TIME 117 8736
TEST_TIME 118 8720
TEST_TIME 119 8678
TEST_TIME 120 8694

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-12-2021 19:39:33 to 16-12-2021 19:39: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 1468466 1468466 0 0.00 0 21039 7 0 0
doc_list_biud 1468466 1468466 0 0.00 0 22710 26 0 0
sp_add_invoice_to_stock 5118 5008 110 2.14 40 209844 18331 0 110
sp_cancel_adding_invoice 5096 5096 0 0.00 5 53776 832 0 0
sp_cancel_client_order 1843 1843 0 0.00 4 9389 383 0 0
sp_cancel_customer_reserve 498491 498491 0 0.00 1 19009 60 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 3 3318 90 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 2 5654 80 0 0
sp_cancel_supplier_invoice 5705 5694 11 0.19 1 16906 793 0 11
sp_cancel_supplier_order 417 417 0 0.00 5 963379 89748 0 0
sp_cancel_write_off 8617 8617 0 0.00 1 14240 114 0 0
sp_client_order 21260 21260 0 0.00 3 18870 334 0 0
sp_customer_reserve 458944 458944 0 0.00 0 182933 183 0 0
sp_fill_shopping_cart 32634 32634 0 0.00 1 19394 40 0 0
sp_fill_shopping_cart_clo_res 458941 458941 0 0.00 0 15079 14 0 0
sp_get_clo_for_invoice 5005 5005 0 0.00 0 25550 320 0 0
sp_kill_cost_storno 14780 14780 0 0.00 0 14238 58 0 0
sp_kill_qstorno_ret_qs2qd 1011710 1011710 0 0.00 0 18969 33 0 0
sp_kill_qty_storno 535504 535504 0 0.00 0 22709 70 0 0
sp_lock_selected_doc 21187 21187 0 0.00 0 6431 6 0 0
sp_make_cost_storno 28725 28725 0 0.00 0 17773 63 0 0
sp_multiply_rows_for_pdistr 28725 28725 0 0.00 0 17571 33 0 0
sp_multiply_rows_for_qdistr 21260 21260 0 0.00 0 18857 147 0 0
sp_pay_from_customer 7291 7291 0 0.00 4 17754 125 0 0
sp_pay_to_supplier 6088 6088 0 0.00 5 28191 150 0 0
sp_payment_common 13379 13379 0 0.00 1 28178 46 0 0
sp_qd_handle_on_cancel_clo 1843 1843 0 0.00 0 9378 341 0 0
sp_qd_handle_on_invoice_upd_sts 10099 10099 0 0.00 0 22709 318 0 0
sp_qd_handle_on_reserve_upd_sts 10343 10343 0 0.00 0 15047 54 0 0
sp_reserve_write_off 10343 10343 0 0.00 6 18586 196 0 0
sp_supplier_invoice 5592 5592 0 0.00 7 75211 3195 0 0
sp_supplier_order 5785 5785 0 0.00 5 27241 1186 0 0
srv_aggregate_perf_data 117 117 0 0.00 2538 220651 16635 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1840 242815 53459 0 0
srv_find_qd_qs_mism 994764 994764 0 0.00 0 21038 6 0 0
srv_make_invnt_saldo 554 554 0 0.00 36 233654 1488 0 0
srv_make_money_saldo 790 790 0 0.00 0 2328 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 185509 312500 231924 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5009 5009 0 0.00 0 22324 161 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 0 3720 80 0 0
t$perf-norm:sp_cancel_client_order 1843 1843 0 0.00 0 13008 24 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1818 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 2237 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 78 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 2082 17 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 25322 1602 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 1530 6 0 0
t$perf-norm:sp_client_order 21260 21260 0 0.00 0 19995 19 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 2394 8 0 0
t$perf-norm:sp_pay_from_customer 7291 7291 0 0.00 0 10458 13 0 0
t$perf-norm:sp_pay_to_supplier 6088 6088 0 0.00 0 8744 12 0 0
t$perf-norm:sp_reserve_write_off 10343 10343 0 0.00 0 8348 13 0 0
t$perf-norm:sp_supplier_invoice 5592 5592 0 0.00 0 5282 20 0 0
t$perf-norm:sp_supplier_order 5785 5785 0 0.00 0 20229 19 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 472 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 3512 65 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 3306 29 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 7533 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10226 10006 220 2.15 0 5356 22 0 220
v_all_customers 38630 38630 0 0.00 0 53 1 0 0
v_all_suppliers 31430 31430 0 0.00 0 52 1 0 0
v_all_wares 42518 42518 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 2453 11 0 0
v_cancel_client_order 3686 3686 0 0.00 0 4741 12 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 1766 7 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 1150 7 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 5097 9 0 0
v_cancel_supplier_invoice 868 846 22 2.53 0 4201 23 0 22
v_cancel_supplier_order 834 834 0 0.00 0 1089 4 0 0
v_cancel_write_off 1996 1996 0 0.00 0 4749 18 0 0
v_max_id_clo_ord 5784 5784 0 0.00 0 5049 2 0 0
v_max_id_clo_res 966 966 0 0.00 0 1530 13 0 0
v_max_id_ord_sup 5591 5591 0 0.00 0 4783 5 0 0
v_max_non_paid_invoice 1748 1748 0 0.00 0 2864 4 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 17665 8 0 0
v_min_id_clo_ord 5784 5784 0 0.00 0 11708 13 0 0
v_min_id_clo_res 966 966 0 0.00 0 4366 9 0 0
v_min_id_ord_sup 5591 5591 0 0.00 0 4203 5 0 0
v_min_non_paid_invoice 6088 6088 0 0.00 0 28175 44 0 0
v_min_non_paid_realizn 7291 7291 0 0.00 0 12345 30 0 0
v_our_firm 10728 10728 0 0.00 0 13 1 0 0
v_reserve_write_off 20686 20686 0 0.00 0 5217 6 0 0
x_lock_depdocs_on_canc_invoice 10367 10367 0 0.00 0 13592 118 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 23864 370 0 0
x_make_qstorno_1000_1200 5785 5785 0 0.00 1 25645 1091 0 0
x_make_qstorno_1200_2000 5592 5592 0 0.00 2 75193 3063 0 0
x_make_qstorno_2100_3300 458944 458944 0 0.00 0 182914 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2021 19:39:37 to 16-12-2021 19:39:41.

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:08:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:00 40149 8192000000 9918335680 10235228160 995369424 10.834 100 82 164 0 1023515376 6776912 21369040
17:10:48 19675 8192000000 9967579552 10295992320 1049799008 11.359 100 86 172 0 1080323792 8977808 21546976
17:12:10 19685 8192000000 9999876592 10336833536 1079773776 11.645 100 86 172 1 1111570736 10171264 21625696
17:13:34 12522 8192000000 10044168128 10395561984 1130151920 12.123 100 82 164 0 1161957472 12379312 19426240
17:14:48 7852 8192000000 10086187696 10447237120 1170554032 12.502 100 76 151 2 1200616784 12657040 17405712
17:15:59 4285 8192000000 10131179216 10503512064 1218223712 12.945 100 75 150 0 1248323984 13490912 16609360
17:17:05 2660 8192000000 10178404496 10552053760 1259686912 13.327 100 82 162 1 1288683808 11318240 17678656
17:18:09 2085 8192000000 10203576640 10584150016 1286791824 13.575 100 92 182 2 1320995920 13741968 20462128
17:19:13 35109 8192000000 10207656992 10598985728 1304310368 13.734 100 71 142 1 1328369200 7497232 16561600
17:20:51 6246 8192000000 10197537424 10612625408 1312787840 13.811 100 28 57 2 1326952400 7779584 6384976
17:21:58 10140 8192000000 10198755824 10619691008 1319272576 13.870 100 15 29 0 1328252880 5881712 3098592
17:23:10 16135 8192000000 10213383856 10635501568 1332356112 13.988 100 17 34 1 1342672112 5909520 4406480
17:24:28 11507 8192000000 10217848368 10645393408 1337302240 14.033 100 30 61 1 1351399376 6433056 7664080
17:25:40 5028 8192000000 10252987600 10651422720 1336107712 14.022 100 71 145 2 1359219216 5992608 17118896
17:26:45 8162 8192000000 10242140000 10658537472 1344921616 14.102 100 23 46 1 1355630752 5487328 5221808
17:27:55 8381 8192000000 10204430672 10622861312 1304442752 13.736 98 15 30 1 1313458288 5552064 3463472
17:29:06 23306 8192000000 10202967872 10614218752 1276505520 13.481 96 43 87 4 1291789984 4624608 10659856
17:30:29 7030 8192000000 10176405136 10586836992 1241610768 13.161 95 27 54 2 1253131360 4892320 6628272
17:31:40 4898 8192000000 10146186048 10544914432 1221706464 12.977 97 27 54 0 1233815280 5503776 6605040
17:32:46 9109 8192000000 10155565184 10558488576 1260424912 13.334 100 25 50 3 1271751440 5215776 6110752
17:33:59 41264 8192000000 10162418944 10575294464 1276088256 13.477 100 25 50 1 1287160176 5174928 5896992
17:35:43 7488 8192000000 10183773200 10597650432 1294207584 13.643 100 33 66 1 1308636640 5752288 8676768
17:36:51 6333 8192000000 10193249312 10606993408 1302205232 13.715 100 21 41 0 1312299632 5308016 4786384
17:38:00 142902 8192000000 10251526000 10668560384 1339524560 14.053 100 40 79 2 1358617360 9176944 9915856
17:41:31 19484 8192000000 10269689088 10686066688 1359056864 14.229 100 37 74 0 1380947120 12717712 9172544
17:42:53 58617 8192000000 10275675632 10704384000 1370926208 14.335 100 40 78 1 1394182160 13757040 9498912
17:45:11 9589 8192000000 10297631216 10724868096 1383059552 14.444 100 51 102 1 1413036288 17144048 12832688
17:46:29 9151 8192000000 10310870576 10734239744 1392035072 14.524 100 53 104 1 1419577584 15078832 12463680
17:47:44 3682 8192000000 10330801120 10746916864 1387547408 14.484 99 71 142 1 1424355744 18955040 17853296
17:48:54 6014 8192000000 10338446560 10765234176 1409876304 14.683 100 59 117 1 1439350800 15708640 13765856
17:50:07 2332 8192000000 10338788048 10753740800 1397723216 14.575 100 87 173 1 1442225472 23151568 21350688
17:51:13 4279 8192000000 10322917392 10739875840 1389095568 14.498 100 74 149 3 1431959024 24186448 18677008
17:52:21 3873 8192000000 10334120096 10742648832 1382922928 14.443 99 77 151 1 1427005968 25907984 18175056
17:53:35 2159 8192000000 10347740528 10762674176 1402374832 14.616 100 75 148 3 1451480128 31049408 18055888
17:54:41 1839 8192000000 10344951472 10751434752 1386216176 14.472 100 83 163 2 1440653568 34679344 19758048
17:55:51 5253 8192000000 10345931296 10764140544 1399803232 14.593 100 59 118 0 1451327152 36092592 15431328
17:57:05 5590 8192000000 10294746880 10710872064 1359973856 14.237 99 49 98 0 1399431312 26395616 13061840
17:58:13 13316 8192000000 10303257312 10730344448 1367341424 14.303 100 42 86 0 1411315920 32184960 11789536
17:59:28 18344 8192000000 10286184576 10707640320 1342353104 14.079 99 35 69 0 1383220544 31064336 9803104
18:00:50 12015 8192000000 10269314048 10700120064 1333825824 14.002 99 31 62 0 1373043072 30687168 8530080
18:02:09 7528 8192000000 10301417632 10724651008 1355014160 14.193 100 45 89 4 1401888288 34758576 12115552
18:03:18 21516 8192000000 10289088368 10729340928 1338419504 14.043 98 22 44 0 1378949056 34114336 6415216
18:04:41 23283 8192000000 10262119376 10675851264 1275335008 13.470 94 49 92 2 1306704688 19999744 11369936
18:06:05 31403 8192000000 10259057584 10692812800 1328321760 13.952 99 24 48 0 1357254736 23164144 5768832
18:07:39 39086 8192000000 10256151920 10684649472 1328363504 13.952 99 15 30 0 1347305904 15632144 3310256
18:09:19 149525 8192000000 10267259568 10708758528 1323999568 13.913 97 19 38 0 1335246224 7220496 4026160
18:12:50 80432 8192000000 10290221696 10736553984 1376305920 14.384 100 33 65 1 1398907696 14365456 8236320
18:15:16 33004 8192000000 10287922896 10738151424 1388257504 14.490 100 19 37 1 1398469728 6291936 3920288
18:16:52 29137 8192000000 10312196272 10743844864 1387404464 14.483 100 50 100 1 1409448512 10482224 11561824
18:18:22 61311 8192000000 10308954048 10760605696 1401897040 14.612 100 23 46 0 1420688640 13264768 5526832
18:20:26 3782 8192000000 10331835136 10769080320 1409168416 14.677 100 61 122 1 1437826928 14845520 13812992
18:21:31 242814 8192000000 10311175728 10772692992 1384586112 14.458 98 17 34 0 1403180480 14404832 4189536
18:27:01 86738 8192000000 10311423312 10764730368 1391099232 14.516 99 29 58 0 1411494032 13100752 7294048
18:29:31 34184 8192000000 10296848432 10750996480 1380897632 14.425 99 32 63 1 1396067664 8087664 7082368
18:31:11 77264 8192000000 10262884176 10718457856 1312408480 13.808 95 14 28 0 1328684048 13904288 2371280
18:33:30 39348 8192000000 10277956464 10722856960 1336741824 14.028 97 11 22 0 1351029248 11832608 2454816
18:35:10 22048 8192000000 10277659744 10713468928 1352578320 14.171 99 33 65 2 1369403712 9842672 6982720
18:36:35 181869 8192000000 10245427856 10686021632 1186418448 12.650 86 17 34 0 1201796864 11533760 3844656
18:41:33 60613 8192000000 10269175632 10704101376 1337660912 14.036 98 35 69 1 1356975888 11746848 7568128
18:43:35 81929 8192000000 10271025440 10729050112 1360806896 14.245 99 11 23 1 1379090512 15598688 2684928
18:45:58 42835 8192000000 10246793296 10694918144 1321169040 13.887 97 12 24 0 1337896880 14019600 2708240
18:47:50 63970 8192000000 10258631968 10702827520 1347272592 14.123 100 32 65 2 1370276208 14781712 8221904
18:50:03 45342 8192000000 10259094848 10698203136 1334150112 14.005 98 24 48 1 1352733168 13101232 5481824
18:51:55 81494 8192000000 10286876544 10734022656 1352914912 14.174 98 18 36 1 1376210464 18859984 4435568
18:54:17 86552 8192000000 10271370368 10735878144 1338025904 14.040 98 9 18 0 1362434608 22362992 2045712
18:56:44 62971 8192000000 10277642112 10710286336 1324888352 13.921 97 39 78 1 1342631744 8658832 9084560
18:58:52 62944 8192000000 10270107824 10706829312 1331807600 13.983 98 23 45 3 1347394848 10770304 4816944
19:00:59 65092 8192000000 10300391984 10739064832 1374058864 14.363 100 18 36 0 1397185632 18656096 4470672
19:03:21 120718 8192000000 10279541232 10732068864 1327057456 13.941 96 30 60 2 1346554720 12975776 6521488
19:06:24 62486 8192000000 10264938368 10722643968 1364298128 14.276 99 12 24 0 1381793888 14814384 2681376
19:08:27 60279 8192000000 10285419408 10743779328 1371494000 14.340 99 12 24 0 1389342400 15134944 2713456
19:10:29 72820 8192000000 10299767344 10766340096 1393981104 14.541 100 11 22 0 1407947312 11622640 2343568
19:12:42 100874 8192000000 10311242000 10762596352 1391986320 14.524 99 20 40 0 1414804960 18233008 4585632
19:15:31 49361 8192000000 10318683744 10776485888 1393853120 14.540 99 26 52 0 1411406336 11034256 6518960
19:17:33 83514 8192000000 10303180672 10747047936 1336582816 14.027 95 19 38 0 1356628720 15590128 4455776
19:20:13 95835 8192000000 10291603264 10749317120 1331061296 13.977 95 30 60 1 1347128720 9555936 6511488
19:22:49 62447 8192000000 10298461856 10752970752 1362237824 14.257 98 21 41 1 1378955600 12004400 4713376
19:24:52 34196 8192000000 10299904688 10748469248 1362010848 14.255 98 21 42 0 1379830464 13375760 4443856
19:26:27 106775 8192000000 10284851280 10724843520 1332706560 13.992 96 25 50 1 1348920560 10879296 5334704
19:29:22 51673 8192000000 10263782608 10712686592 1343046960 14.085 98 26 52 0 1358897488 10377328 5473200
19:31:17 55854 8192000000 10255825344 10718367744 1333483792 13.999 97 6 12 0 1344443680 9202288 1757600
19:33:13 53082 8192000000 10240330400 10694615040 1300788784 13.702 95 13 25 0 1309113808 5416528 2908496
19:35:09 78025 8192000000 10231973664 10690646016 1326447088 13.935 99 17 33 1 1337303872 7233536 3623248
19:37:27 36591 8192000000 9397477488 9712746496 545870112 6.247 41 4 8 0 551586384 4883792 832480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-12-2021 19:39:43 to 16-12-2021 19:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2021 19:39:58 to 16-12-2021 19:40:00.

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          241890
MON$OLDEST_ACTIVE               242266
MON$OLDEST_SNAPSHOT             242266
MON$NEXT_TRANSACTION            242266
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                       2012672
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33545 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33545 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33545 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33545 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Thu Dec 16 19:38:09 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

16.12.21 19:40:01. 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-12-16 19:40:04 No severe PSQL-related problems occured
16.12.21 19:40:05. End of report.