Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.12.2021 19:40
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 16666580 kB
MemAvailable 31405452 kB
Buffers 0 kB
Cached 15036716 kB
SwapCached 440 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-25 19:37:58
DTS_END                         2021-12-25 19:37:58.9210
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/53024
TRN_ID                          237033
ATT_ID                          694
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: 9050.78 18040 1086094
customer order: creation 174.03 363 20883
customer order: refuse 14.35 360 1722
order to supplier: creation 45.13 1325 5415
order to supplier: removal 3.17 113956 380
invoice (draft): creation 45.23 3586 5428
invoice (draft): removal 50.77 836 6092
invoice accept: apply 42.00 20184 5040
invoice accept: cancel 45.57 873 5468
customer reserve: creation 3882.48 201 465897
customer reserve: removal 4451.13 62 534135
realization accept: apply 84.16 190 10099
realization accept: cancel 81.98 120 9837
payment from customer: creation 60.26 127 7231
payment from customer: removal 5.67 101 680
payment to supplier: creation 49.25 137 5910
payment to supplier: removal 3.30 85 396
service: total inventory turnovers 4.70 1156 564
service: total monetary turnovers 6.66 34 799
service: aggregate perf. data 0.95 14086 114
service: refresh index statistics 0.03 203018 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-12-2021 19:40:23 to 25-12-2021 19:40:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4507
TEST_TIME 2 5002
TEST_TIME 3 5752
TEST_TIME 4 5144
TEST_TIME 5 4758
TEST_TIME 6 5523
TEST_TIME 7 5718
TEST_TIME 8 6727
TEST_TIME 9 6623
TEST_TIME 10 6749
TEST_TIME 11 7042
TEST_TIME 12 7108
TEST_TIME 13 6981
TEST_TIME 14 6890
TEST_TIME 15 7235
TEST_TIME 16 7126
TEST_TIME 17 7167
TEST_TIME 18 7410
TEST_TIME 19 7573
TEST_TIME 20 7743
TEST_TIME 21 8131
TEST_TIME 22 8489
TEST_TIME 23 9010
TEST_TIME 24 8942
TEST_TIME 25 9245
TEST_TIME 26 9374
TEST_TIME 27 9624
TEST_TIME 28 9556
TEST_TIME 29 9887
TEST_TIME 30 10788
TEST_TIME 31 10552
TEST_TIME 32 10486
TEST_TIME 33 10443
TEST_TIME 34 10179
TEST_TIME 35 10312
TEST_TIME 36 10132
TEST_TIME 37 10040
TEST_TIME 38 9884
TEST_TIME 39 9844
TEST_TIME 40 9686
TEST_TIME 41 9649
TEST_TIME 42 9592
TEST_TIME 43 9790
TEST_TIME 44 9712
TEST_TIME 45 9625
TEST_TIME 46 9541
TEST_TIME 47 9414
TEST_TIME 48 9416
TEST_TIME 49 9539
TEST_TIME 50 9557
TEST_TIME 51 9537
TEST_TIME 52 9570
TEST_TIME 53 9596
TEST_TIME 54 9495
TEST_TIME 55 9514
TEST_TIME 56 9494
TEST_TIME 57 9545
TEST_TIME 58 9536
TEST_TIME 59 9556
TEST_TIME 60 9497
TEST_TIME 61 9495
TEST_TIME 62 9502
TEST_TIME 63 9372
TEST_TIME 64 9336
TEST_TIME 65 9248
TEST_TIME 66 9274
TEST_TIME 67 9225
TEST_TIME 68 9201
TEST_TIME 69 9178
TEST_TIME 70 9121
TEST_TIME 71 9062
TEST_TIME 72 9065
TEST_TIME 73 9172
TEST_TIME 74 9165
TEST_TIME 75 9207
TEST_TIME 76 9212
TEST_TIME 77 9206
TEST_TIME 78 9205
TEST_TIME 79 9221
TEST_TIME 80 9191
TEST_TIME 81 9237
TEST_TIME 82 9257
TEST_TIME 83 9285
TEST_TIME 84 9290
TEST_TIME 85 9278
TEST_TIME 86 9321
TEST_TIME 87 9235
TEST_TIME 88 9267
TEST_TIME 89 9268
TEST_TIME 90 9212
TEST_TIME 91 9155
TEST_TIME 92 9122
TEST_TIME 93 9099
TEST_TIME 94 9128
TEST_TIME 95 9082
TEST_TIME 96 9114
TEST_TIME 97 9125
TEST_TIME 98 9110
TEST_TIME 99 9173
TEST_TIME 100 9116
TEST_TIME 101 9140
TEST_TIME 102 9141
TEST_TIME 103 9285
TEST_TIME 104 9254
TEST_TIME 105 9241
TEST_TIME 106 9255
TEST_TIME 107 9280
TEST_TIME 108 9246
TEST_TIME 109 9214
TEST_TIME 110 9199
TEST_TIME 111 9155
TEST_TIME 112 9126
TEST_TIME 113 9104
TEST_TIME 114 9101
TEST_TIME 115 9129
TEST_TIME 116 9120
TEST_TIME 117 9117
TEST_TIME 118 9086
TEST_TIME 119 9091
TEST_TIME 120 9061

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-12-2021 19:40:27 to 25-12-2021 19:40:30.

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 1516309 1516309 0 0.00 0 20986 7 0 0
doc_list_biud 1516309 1516309 0 0.00 0 42370 28 0 0
sp_add_invoice_to_stock 5141 5040 101 1.96 38 194174 20185 0 101
sp_cancel_adding_invoice 5468 5468 0 0.00 5 61490 873 0 0
sp_cancel_client_order 1722 1722 0 0.00 3 11488 360 0 0
sp_cancel_customer_reserve 534135 534135 0 0.00 1 42372 63 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 6917 102 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 2 2771 86 0 0
sp_cancel_supplier_invoice 6102 6092 10 0.16 1 37864 837 0 10
sp_cancel_supplier_order 380 380 0 0.00 8 1071757 113956 0 0
sp_cancel_write_off 9837 9837 0 0.00 1 19094 121 0 0
sp_client_order 20883 20883 0 0.00 3 37907 363 0 0
sp_customer_reserve 465897 465897 0 0.00 0 67794 201 0 0
sp_fill_shopping_cart 31721 31721 0 0.00 1 14124 40 0 0
sp_fill_shopping_cart_clo_res 465887 465887 0 0.00 0 12909 12 0 0
sp_get_clo_for_invoice 5030 5030 0 0.00 0 18188 287 0 0
sp_kill_cost_storno 16381 16381 0 0.00 0 19064 66 0 0
sp_kill_qstorno_ret_qs2qd 1084579 1084579 0 0.00 0 42367 34 0 0
sp_kill_qty_storno 572763 572763 0 0.00 0 42370 74 0 0
sp_lock_selected_doc 20789 20789 0 0.00 0 8866 4 0 0
sp_make_cost_storno 28270 28270 0 0.00 0 13248 55 0 0
sp_multiply_rows_for_pdistr 28270 28270 0 0.00 0 14015 32 0 0
sp_multiply_rows_for_qdistr 20883 20883 0 0.00 0 12900 155 0 0
sp_pay_from_customer 7231 7231 0 0.00 4 28609 127 0 0
sp_pay_to_supplier 5910 5910 0 0.00 4 37663 137 0 0
sp_payment_common 13141 13141 0 0.00 1 17575 30 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 11480 341 0 0
sp_qd_handle_on_invoice_upd_sts 10498 10498 0 0.00 0 36267 370 0 0
sp_qd_handle_on_reserve_upd_sts 10099 10099 0 0.00 0 13107 64 0 0
sp_reserve_write_off 10099 10099 0 0.00 6 20039 191 0 0
sp_supplier_invoice 5428 5428 0 0.00 6 69976 3587 0 0
sp_supplier_order 5415 5415 0 0.00 5 30671 1325 0 0
srv_aggregate_perf_data 114 114 0 0.00 2196 126408 14086 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1298 134177 47569 0 0
srv_find_qd_qs_mism 1037431 1037431 0 0.00 0 20986 7 0 0
srv_make_invnt_saldo 564 564 0 0.00 41 64112 1156 0 0
srv_make_money_saldo 799 799 0 0.00 1 1974 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 80215 355682 203018 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 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 5041 5041 0 0.00 0 35046 214 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 5972 137 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 5462 17 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 5637 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 7248 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 2432 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 877 13 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 42065 1968 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 4374 16 0 0
t$perf-norm:sp_client_order 20883 20883 0 0.00 0 23173 22 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 2346 10 0 0
t$perf-norm:sp_pay_from_customer 7231 7231 0 0.00 0 15449 16 0 0
t$perf-norm:sp_pay_to_supplier 5910 5910 0 0.00 0 13496 16 0 0
t$perf-norm:sp_reserve_write_off 10099 10099 0 0.00 0 22508 18 0 0
t$perf-norm:sp_supplier_invoice 5428 5428 0 0.00 0 6532 23 0 0
t$perf-norm:sp_supplier_order 5415 5415 0 0.00 0 11195 24 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 158 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 7 1 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 1369 28 0 0
t$perf-norm:srv_make_money_saldo 799 799 0 0.00 0 1071 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10262 10060 202 1.96 0 6164 19 0 202
v_all_customers 37774 37774 0 0.00 0 19 1 0 0
v_all_suppliers 30222 30222 0 0.00 0 17 1 0 0
v_all_wares 41764 41764 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 432 7 0 0
v_cancel_client_order 3444 3444 0 0.00 0 780 3 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 3863 15 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 1944 5 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2550 8 0 0
v_cancel_supplier_invoice 862 842 20 2.32 0 1799 18 0 20
v_cancel_supplier_order 760 760 0 0.00 0 1262 8 0 0
v_cancel_write_off 1930 1930 0 0.00 0 2414 15 0 0
v_max_id_clo_ord 5415 5415 0 0.00 0 930 2 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 11234 23 0 0
v_max_id_ord_sup 5424 5424 0 0.00 0 2513 3 0 0
v_max_non_paid_invoice 1638 1638 0 0.00 0 383 2 0 0
v_max_non_paid_realizn 3870 3870 0 0.00 0 2982 4 0 0
v_min_id_clo_ord 5415 5415 0 0.00 0 3321 10 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 6057 23 0 0
v_min_id_ord_sup 5424 5424 0 0.00 0 6978 7 0 0
v_min_non_paid_invoice 5910 5910 0 0.00 0 17567 22 0 0
v_min_non_paid_realizn 7231 7231 0 0.00 0 13980 25 0 0
v_our_firm 10712 10712 0 0.00 0 21 1 0 0
v_reserve_write_off 20198 20198 0 0.00 0 5272 6 0 0
x_lock_depdocs_on_canc_invoice 11139 11139 0 0.00 0 13478 133 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 7227 340 0 0
x_make_qstorno_1000_1200 5415 5415 0 0.00 1 30663 1236 0 0
x_make_qstorno_1200_2000 5428 5428 0 0.00 2 69957 3447 0 0
x_make_qstorno_2100_3300 465897 465897 0 0.00 0 67793 186 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-12-2021 19:40:31 to 25-12-2021 19:40:35.

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:02 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:03 24416 8192000000 9903132448 10219757568 981435168 10.698 100 82 164 0 1008329760 5487952 21406640
17:10:34 21243 8192000000 9966583856 10292924416 1048067488 11.342 100 91 182 0 1079526576 8060560 23398528
17:12:01 12986 8192000000 9999103664 10337341440 1082819680 11.674 100 84 168 0 1113225808 9202704 21203424
17:13:17 12255 8192000000 10043220064 10399465472 1132161856 12.142 100 74 148 0 1160397648 10518000 17717792
17:14:33 8211 8192000000 10092053024 10452398080 1177507360 12.567 100 84 168 3 1209484704 12464528 19512816
17:15:45 5929 8192000000 10132152816 10502582272 1219796976 12.960 100 77 153 0 1249759328 12994080 16968272
17:16:52 2720 8192000000 10166283648 10541236224 1251461424 13.252 100 83 166 0 1283456080 13075584 18919072
17:17:57 2161 8192000000 10193556144 10570014720 1275778464 13.474 100 90 178 0 1309388176 13717680 19892032
17:19:01 2710 8192000000 10209321872 10593017856 1290545072 13.609 100 89 177 0 1323859024 13344256 19969696
17:20:04 2226 8192000000 10220604528 10608058368 1303982544 13.731 100 86 172 1 1336542144 13214624 19344976
17:21:11 33204 8192000000 10205140032 10612129792 1308866096 13.776 100 47 94 2 1327884880 7024320 11994464
17:22:45 8222 8192000000 10201732720 10619789312 1316216944 13.842 100 25 50 0 1328620368 6895968 5507456
17:23:54 11539 8192000000 10210420400 10630942720 1326907472 13.939 100 13 26 0 1335486320 5784848 2794000
17:25:06 12542 8192000000 10215942192 10637123584 1330255600 13.969 100 10 20 0 1338393136 5773440 2364096
17:26:19 42867 8192000000 10219467824 10640453632 1320412608 13.880 99 40 80 3 1339675200 9705808 9556784
17:28:02 7072 8192000000 10200951440 10622672896 1313067472 13.814 100 19 38 1 1323108080 5723408 4317200
17:29:11 5587 8192000000 10191570304 10601394176 1274925504 13.467 98 30 59 4 1288613136 6221312 7466320
17:30:22 67413 8192000000 10149727552 10546876416 1162601680 12.428 91 33 63 2 1175768752 4543152 8623920
17:32:31 8428 8192000000 10173820832 10579402752 1252434144 13.261 98 24 47 0 1267552608 10194864 4923600
17:33:42 10008 8192000000 10182526080 10586054656 1281797856 13.529 100 28 56 0 1294621408 6490464 6333088
17:34:53 9054 8192000000 10185341120 10588139520 1276209024 13.478 99 22 44 1 1286372320 5045600 5117696
17:36:03 41549 8192000000 10190983072 10609131520 1300872496 13.703 100 29 58 1 1312957792 5676304 6408992
17:37:45 8198 8192000000 10206264848 10620190720 1308784640 13.775 100 29 57 1 1321045328 5946368 6314320
17:39:01 4203 8192000000 10253018320 10660560896 1340640624 14.063 100 52 102 0 1362058976 10208832 11209520
17:40:13 27934 8192000000 10257624368 10677313536 1342916896 14.084 99 49 98 0 1366322016 11665296 11739824
17:41:45 31977 8192000000 10264883376 10686185472 1347953312 14.129 99 38 76 0 1368194960 11415792 8825856
17:43:27 11515 8192000000 10276725456 10701393920 1370743728 14.334 100 40 78 1 1392297488 11796896 9756864
17:44:44 7094 8192000000 10304893568 10717962240 1377512880 14.394 100 63 123 1 1408337584 17170544 13654160
17:45:58 10072 8192000000 10299641472 10714640384 1378925312 14.407 100 61 122 0 1408466976 15921888 13619776
17:47:12 8331 8192000000 10318687344 10740985856 1388596464 14.493 100 52 104 1 1425577712 25106352 11874896
17:48:24 2555 8192000000 10324178736 10741948416 1396849120 14.567 100 71 141 1 1432343824 19509392 15985312
17:49:30 8193 8192000000 10322119744 10738356224 1376056624 14.381 98 71 142 0 1413108704 20107152 16944928
17:50:42 5182 8192000000 10324638464 10745008128 1399935184 14.594 100 63 124 0 1439755296 24378496 15441616
17:51:52 7508 8192000000 10358483952 10774704128 1412486960 14.706 100 59 118 0 1457357872 30274736 14596176
17:53:06 2414 8192000000 10349231904 10760507392 1398190544 14.579 99 82 163 2 1445292896 26743424 20358928
17:54:13 4267 8192000000 10372407264 10783760384 1419475824 14.768 100 87 172 3 1475299760 34237856 21586080
17:55:31 4243 8192000000 10366405696 10776047616 1411474192 14.697 100 84 168 0 1468044992 35092016 21478784
17:56:50 2853 8192000000 10350930048 10756669440 1391932304 14.523 100 81 163 0 1453800944 40706128 21162512
17:58:00 1298 8192000000 10359415664 10760278016 1389695648 14.503 100 90 178 1 1459083904 46049120 23339136
17:59:07 15399 8192000000 10356118720 10783674368 1402023968 14.613 100 59 117 0 1459775680 42815184 14936528
18:00:31 5222 8192000000 10361346464 10778546176 1389608544 14.502 99 64 127 1 1454252864 48086272 16558048
18:01:49 13893 8192000000 10341793488 10767605760 1383382816 14.447 99 41 82 2 1438054688 44193024 10478848
18:03:05 9341 8192000000 10340572896 10771820544 1388056384 14.489 100 30 60 3 1440314208 44069568 8188256
18:04:27 17883 8192000000 10330521968 10775535616 1388447184 14.492 100 25 50 1 1441188960 45794752 6947024
18:05:49 43024 8192000000 10308151856 10749943808 1322881568 13.903 97 22 44 0 1379054848 49469520 6703760
18:09:10 68664 8192000000 10284776704 10721280000 1347610816 14.126 98 18 35 2 1358879088 7699856 3568416
18:11:24 57625 8192000000 10257998624 10696175616 1344934752 14.102 98 31 62 1 1362064336 9992080 7137504
18:13:23 105854 8192000000 10262578320 10712133632 1344191120 14.095 98 38 73 0 1360761040 8800352 7769568
18:16:51 55842 8192000000 10308565248 10750894080 1376164544 14.382 99 30 58 0 1398764992 16093328 6507120
18:18:54 30279 8192000000 10313225856 10763337728 1393898064 14.541 100 46 92 1 1424975840 20436416 10641360
18:20:25 69027 8192000000 10289895696 10758483968 1383871536 14.451 99 10 18 3 1404320032 18340320 2108176
18:22:38 49929 8192000000 10293270576 10759712768 1383947120 14.452 99 12 24 1 1398493680 11705952 2840608
18:24:28 64662 8192000000 10305953456 10770800640 1399844608 14.594 100 27 53 0 1415088944 9207520 6036816
18:26:36 56859 8192000000 10301011312 10765582336 1387725024 14.486 99 9 18 0 1402442256 12236256 2480976
18:28:34 57290 8192000000 10301426192 10766929920 1393773552 14.540 100 13 26 1 1405670624 8956944 2940128
18:30:46 58972 8192000000 10301060272 10744066048 1356298336 14.204 97 24 47 1 1370464128 8844208 5321584
18:32:49 85961 8192000000 10295827536 10751774720 1357814032 14.218 97 12 25 0 1375365968 14397648 3154288
18:35:16 33174 8192000000 10296739488 10749456384 1379195472 14.409 99 27 54 0 1394365632 9252464 5917696
18:36:51 50260 8192000000 10283721264 10723979264 1313578176 13.819 95 24 48 1 1328365488 9682048 5105264
18:38:41 31566 8192000000 10289249264 10727555072 1359899936 14.236 99 36 72 2 1379142144 11544896 7697312
18:41:49 79352 8192000000 10274500336 10712727552 1328165248 13.951 96 37 74 1 1347995472 12050944 7779280
18:44:10 83871 8192000000 10251933872 10708844544 1306363584 13.753 95 10 20 0 1318952608 10034160 2554864
18:46:39 68190 8192000000 10249533904 10707611648 1329534912 13.963 97 15 30 1 1346247904 13011920 3701072
18:49:41 50153 8192000000 10240817456 10704633856 1332193264 13.987 99 15 30 0 1352974736 16705840 4075632
18:51:35 41753 8192000000 10254760544 10701504512 1324806672 13.920 98 32 64 0 1344541456 12379280 7355504
18:53:21 60614 8192000000 10267427840 10709270528 1345311056 14.105 99 21 42 1 1361225936 10965696 4949184
18:55:24 34917 8192000000 10262338256 10700369920 1317684720 13.856 97 21 42 0 1330004592 7600688 4719184
18:57:35 66030 8192000000 10280421824 10718453760 1343799984 14.092 99 34 67 0 1365551872 14821920 6929968
18:59:50 99025 8192000000 10297209952 10733318144 1340043536 14.058 98 23 46 1 1359754640 14176512 5534592
19:03:32 134177 8192000000 10277542944 10730364928 1356655840 14.207 99 20 40 1 1380723680 19529824 4538016
19:06:46 51207 8192000000 10282672368 10741264384 1377681360 14.396 100 10 20 0 1395206176 15594096 1930720
19:08:41 97386 8192000000 10281793616 10739982336 1363611280 14.270 98 8 16 0 1376700160 11405248 1683632
19:11:39 68809 8192000000 10298465360 10747133952 1377707504 14.396 99 20 38 0 1390963984 9245088 4011392
19:13:49 36885 8192000000 10288239616 10742587392 1375323328 14.375 99 12 24 2 1385328896 7017856 2987712
19:15:27 54550 8192000000 10315092832 10769166336 1403810016 14.629 100 15 31 0 1419890128 12416176 3663936
19:17:35 122959 8192000000 10309467360 10768760832 1392507344 14.528 99 14 28 0 1411524496 15548368 3468784
19:20:51 114605 8192000000 10294620832 10740137984 1331107456 13.977 96 10 20 0 1345599920 12468144 2024320
19:23:50 98569 8192000000 10284667840 10732183552 1326165264 13.932 96 19 38 0 1343139632 12696416 4277952
19:26:31 125846 8192000000 10286170304 10749206528 1367597056 14.306 99 20 40 0 1389140960 17104864 4439040
19:29:48 55463 8192000000 10271621552 10737135616 1345460064 14.107 98 17 34 0 1370874720 21003568 4411088
19:33:03 47812 8192000000 10245955840 10697023488 1296221392 13.661 95 12 23 2 1305808560 6770208 2816960
19:35:15 93967 8192000000 10259614608 10719268864 1332054960 13.986 97 23 46 0 1348033664 10340224 5638480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-12-2021 19:40:37 to 25-12-2021 19:40:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 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 25-12-2021 19:40:52 to 25-12-2021 19:40:54.

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          236252
MON$OLDEST_ACTIVE               237162
MON$OLDEST_SNAPSHOT             237162
MON$NEXT_TRANSACTION            237162
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                       2024256
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.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 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 ---
:131: 
:132: mscs-test-161.budzdorov.ru    Sat Dec 25 19:38:25 2021
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:134: 
:135: 
:136: mscs-test-161.budzdorov.ru    Sat Dec 25 19:38:26 2021
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:138: 
--- end of diff output ---

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

25.12.21 19:40:55. 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-25 19:40:58 No severe PSQL-related problems occured
25.12.21 19:40:59. End of report.