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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16553648 kB
MemAvailable 31489052 kB
Buffers 0 kB
Cached 15131796 kB
SwapCached 2688 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.10
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 2022-04-15 19:38:10
DTS_END                         2022-04-15 19:38:10.2310
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41734
TRN_ID                          236669
ATT_ID                          623
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: 9239.25 25718 1108710
customer order: creation 169.93 336 20392
customer order: refuse 13.90 368 1668
order to supplier: creation 46.43 1343 5571
order to supplier: removal 3.12 111039 374
invoice (draft): creation 46.17 3518 5540
invoice (draft): removal 51.14 846 6137
invoice accept: apply 42.83 17406 5139
invoice accept: cancel 46.65 807 5598
customer reserve: creation 3917.08 172 470049
customer reserve: removal 4615.29 58 553835
realization accept: apply 81.20 185 9744
realization accept: cancel 76.49 109 9179
payment from customer: creation 58.47 115 7016
payment from customer: removal 5.98 70 717
payment to supplier: creation 48.43 134 5812
payment to supplier: removal 3.62 60 434
service: total inventory turnovers 4.70 1347 564
service: total monetary turnovers 7.00 30 840
service: aggregate perf. data 0.81 19402 97
service: refresh index statistics 0.03 357016 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-04-2022 19:39:07 to 15-04-2022 19:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5181
TEST_TIME 2 4325
TEST_TIME 3 4949
TEST_TIME 4 4706
TEST_TIME 5 4977
TEST_TIME 6 5994
TEST_TIME 7 6050
TEST_TIME 8 7116
TEST_TIME 9 6898
TEST_TIME 10 6623
TEST_TIME 11 6688
TEST_TIME 12 6984
TEST_TIME 13 6857
TEST_TIME 14 6807
TEST_TIME 15 7000
TEST_TIME 16 7340
TEST_TIME 17 8140
TEST_TIME 18 8093
TEST_TIME 19 8293
TEST_TIME 20 8570
TEST_TIME 21 8644
TEST_TIME 22 8656
TEST_TIME 23 8766
TEST_TIME 24 9217
TEST_TIME 25 9026
TEST_TIME 26 9241
TEST_TIME 27 9225
TEST_TIME 28 9026
TEST_TIME 29 9357
TEST_TIME 30 9463
TEST_TIME 31 9779
TEST_TIME 32 10488
TEST_TIME 33 10914
TEST_TIME 34 10812
TEST_TIME 35 10898
TEST_TIME 36 10885
TEST_TIME 37 10773
TEST_TIME 38 10545
TEST_TIME 39 10541
TEST_TIME 40 10351
TEST_TIME 41 10308
TEST_TIME 42 10309
TEST_TIME 43 10137
TEST_TIME 44 10018
TEST_TIME 45 9870
TEST_TIME 46 9947
TEST_TIME 47 9799
TEST_TIME 48 9911
TEST_TIME 49 9750
TEST_TIME 50 9654
TEST_TIME 51 9727
TEST_TIME 52 9581
TEST_TIME 53 9507
TEST_TIME 54 9445
TEST_TIME 55 9372
TEST_TIME 56 9370
TEST_TIME 57 9388
TEST_TIME 58 9421
TEST_TIME 59 9335
TEST_TIME 60 9264
TEST_TIME 61 9187
TEST_TIME 62 9093
TEST_TIME 63 9154
TEST_TIME 64 9181
TEST_TIME 65 9242
TEST_TIME 66 9250
TEST_TIME 67 9224
TEST_TIME 68 9162
TEST_TIME 69 9287
TEST_TIME 70 9339
TEST_TIME 71 9318
TEST_TIME 72 9297
TEST_TIME 73 9321
TEST_TIME 74 9354
TEST_TIME 75 9404
TEST_TIME 76 9370
TEST_TIME 77 9384
TEST_TIME 78 9280
TEST_TIME 79 9290
TEST_TIME 80 9265
TEST_TIME 81 9209
TEST_TIME 82 9241
TEST_TIME 83 9163
TEST_TIME 84 9178
TEST_TIME 85 9222
TEST_TIME 86 9142
TEST_TIME 87 9111
TEST_TIME 88 9122
TEST_TIME 89 9105
TEST_TIME 90 9077
TEST_TIME 91 9099
TEST_TIME 92 9165
TEST_TIME 93 9162
TEST_TIME 94 9219
TEST_TIME 95 9225
TEST_TIME 96 9217
TEST_TIME 97 9326
TEST_TIME 98 9334
TEST_TIME 99 9394
TEST_TIME 100 9396
TEST_TIME 101 9394
TEST_TIME 102 9362
TEST_TIME 103 9291
TEST_TIME 104 9316
TEST_TIME 105 9273
TEST_TIME 106 9245
TEST_TIME 107 9232
TEST_TIME 108 9199
TEST_TIME 109 9171
TEST_TIME 110 9163
TEST_TIME 111 9190
TEST_TIME 112 9195
TEST_TIME 113 9189
TEST_TIME 114 9197
TEST_TIME 115 9171
TEST_TIME 116 9263
TEST_TIME 117 9315
TEST_TIME 118 9282
TEST_TIME 119 9308
TEST_TIME 120 9240

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-04-2022 19:39:10 to 15-04-2022 19:39:14.

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 1542728 1542728 0 0.00 0 16241 6 0 0
doc_list_biud 1542727 1542727 0 0.00 0 29515 27 0 0
sp_add_invoice_to_stock 5268 5139 129 2.44 53 179317 17407 0 129
sp_cancel_adding_invoice 5598 5598 0 0.00 3 71205 808 0 0
sp_cancel_client_order 1668 1668 0 0.00 4 20187 368 0 0
sp_cancel_customer_reserve 553835 553835 0 0.00 1 29518 58 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 3 4671 71 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 2 1508 60 0 0
sp_cancel_supplier_invoice 6151 6137 14 0.22 1 15578 846 0 14
sp_cancel_supplier_order 374 374 0 0.00 6 980854 111039 0 0
sp_cancel_write_off 9179 9179 0 0.00 1 9282 110 0 0
sp_client_order 20392 20392 0 0.00 4 19259 336 0 0
sp_customer_reserve 470049 470049 0 0.00 0 65295 172 0 0
sp_fill_shopping_cart 31489 31489 0 0.00 1 15239 44 0 0
sp_fill_shopping_cart_clo_res 470046 470046 0 0.00 0 20149 12 0 0
sp_get_clo_for_invoice 5136 5136 0 0.00 0 21131 308 0 0
sp_kill_cost_storno 15928 15928 0 0.00 0 8964 61 0 0
sp_kill_qstorno_ret_qs2qd 1123360 1123360 0 0.00 0 29510 32 0 0
sp_kill_qty_storno 591670 591670 0 0.00 0 29514 70 0 0
sp_lock_selected_doc 20544 20544 0 0.00 0 7603 4 0 0
sp_make_cost_storno 27708 27708 0 0.00 0 16204 56 0 0
sp_multiply_rows_for_pdistr 27707 27707 0 0.00 0 13853 36 0 0
sp_multiply_rows_for_qdistr 20392 20392 0 0.00 0 10332 137 0 0
sp_pay_from_customer 7016 7016 0 0.00 4 17016 115 0 0
sp_pay_to_supplier 5812 5812 0 0.00 4 13853 134 0 0
sp_payment_common 12828 12828 0 0.00 2 17001 29 0 0
sp_qd_handle_on_cancel_clo 1668 1668 0 0.00 0 20177 346 0 0
sp_qd_handle_on_invoice_upd_sts 10733 10733 0 0.00 0 25809 371 0 0
sp_qd_handle_on_reserve_upd_sts 9744 9744 0 0.00 0 18505 60 0 0
sp_reserve_write_off 9744 9744 0 0.00 6 18991 186 0 0
sp_supplier_invoice 5540 5540 0 0.00 6 90852 3519 0 0
sp_supplier_order 5571 5571 0 0.00 5 36065 1343 0 0
srv_aggregate_perf_data 97 97 0 0.00 2862 214695 19402 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2249 141469 46263 0 0
srv_find_qd_qs_mism 1060216 1060216 0 0.00 0 15548 5 0 0
srv_make_invnt_saldo 564 564 0 0.00 43 115385 1347 0 0
srv_make_money_saldo 840 840 0 0.00 0 2106 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 243373 502363 357016 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5139 5139 0 0.00 0 21347 174 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 5751 100 0 0
t$perf-norm:sp_cancel_client_order 1668 1668 0 0.00 0 5284 18 0 0
t$perf-norm:sp_cancel_customer_reserve 674 674 0 0.00 0 753 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 3183 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 494 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 971 16 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 34687 1922 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 1369 6 0 0
t$perf-norm:sp_client_order 20392 20392 0 0.00 0 8704 20 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 7260 17 0 0
t$perf-norm:sp_pay_from_customer 7016 7016 0 0.00 0 9927 12 0 0
t$perf-norm:sp_pay_to_supplier 5812 5812 0 0.00 0 4348 13 0 0
t$perf-norm:sp_reserve_write_off 9744 9744 0 0.00 0 9903 14 0 0
t$perf-norm:sp_supplier_invoice 5540 5540 0 0.00 0 9551 23 0 0
t$perf-norm:sp_supplier_order 5571 5571 0 0.00 0 3860 18 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 561 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 199 5 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 1231 20 0 0
t$perf-norm:srv_make_money_saldo 840 840 0 0.00 0 1472 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 10528 10270 258 2.45 0 7741 18 0 258
v_all_customers 37008 37008 0 0.00 0 18 1 0 0
v_all_suppliers 30478 30478 0 0.00 0 25 1 0 0
v_all_wares 40784 40784 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 3059 8 0 0
v_cancel_client_order 3336 3336 0 0.00 0 4557 6 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 4633 5 0 0
v_cancel_customer_reserve 1348 1348 0 0.00 0 2281 5 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 195 3 0 0
v_cancel_supplier_invoice 784 756 28 3.57 0 466 13 0 28
v_cancel_supplier_order 748 748 0 0.00 0 228 3 0 0
v_cancel_write_off 2038 2038 0 0.00 0 1969 12 0 0
v_max_id_clo_ord 5564 5564 0 0.00 0 1349 2 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 1506 10 0 0
v_max_id_ord_sup 5532 5532 0 0.00 0 3673 3 0 0
v_max_non_paid_invoice 1669 1669 0 0.00 0 3093 4 0 0
v_max_non_paid_realizn 3709 3709 0 0.00 0 2137 6 0 0
v_min_id_clo_ord 5564 5564 0 0.00 0 3234 7 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 8803 19 0 0
v_min_id_ord_sup 5532 5532 0 0.00 0 5554 6 0 0
v_min_non_paid_invoice 5812 5812 0 0.00 0 10266 17 0 0
v_min_non_paid_realizn 7016 7016 0 0.00 0 16995 25 0 0
v_our_firm 10390 10390 0 0.00 0 14 1 0 0
v_reserve_write_off 19488 19488 0 0.00 0 7805 7 0 0
x_lock_depdocs_on_canc_invoice 11357 11357 0 0.00 0 19214 120 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 10237 394 0 0
x_make_qstorno_1000_1200 5571 5571 0 0.00 2 36032 1247 0 0
x_make_qstorno_1200_2000 5540 5540 0 0.00 2 77576 3369 0 0
x_make_qstorno_2100_3300 470049 470049 0 0.00 0 65293 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-04-2022 19:39:15 to 15-04-2022 19:39:19.

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:14 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:15 31758 8192000000 9899418144 10210324480 981357600 10.697 100 89 177 0 1010646400 5950112 23338688
17:10:52 23119 8192000000 9938603120 10258874368 1016884912 11.042 100 89 178 0 1048301456 8029040 23387504
17:12:19 21736 8192000000 9974676000 10304745472 1057307472 11.431 100 84 168 0 1088528112 10127328 21093312
17:13:46 12871 8192000000 10026112928 10369155072 1106924336 11.903 100 76 152 0 1135686496 10584832 18177328
17:15:03 9378 8192000000 10077131344 10430963712 1162478336 12.426 100 84 168 0 1194643152 12789984 19374832
17:16:14 6154 8192000000 10123971216 10487726080 1211485648 12.883 100 86 172 2 1244395264 14445952 18463664
17:17:22 2463 8192000000 10162150720 10536001536 1250507200 13.243 100 92 183 0 1284457120 13885056 20064864
17:18:25 2258 8192000000 10194893920 10576158720 1278473760 13.499 100 93 186 5 1311517424 12594320 20449344
17:19:29 1660 8192000000 10220496080 10601619456 1301941408 13.713 100 96 192 0 1337721760 14228144 21552208
17:20:31 22106 8192000000 10225711536 10613182464 1313895840 13.821 100 81 165 1 1340332704 6126752 20310112
17:21:53 4610 8192000000 10216141152 10623332352 1315561024 13.836 100 36 72 1 1331015392 7499360 7955008
17:23:00 10862 8192000000 10206378944 10624839680 1321931632 13.894 100 15 30 0 1330649904 5634208 3084064
17:24:11 8477 8192000000 10205836816 10625912832 1323651648 13.910 100 22 43 2 1334556720 5717376 5187696
17:25:21 54011 8192000000 10225641136 10630037504 1318771360 13.866 99 29 58 1 1330833424 5287152 6774912
17:27:38 12816 8192000000 10228253088 10645524480 1323224384 13.906 99 36 72 1 1337218480 5688144 8305952
17:29:09 5386 8192000000 10211119280 10629947392 1319638560 13.873 100 36 71 2 1334692128 7091472 7962096
17:30:18 9348 8192000000 10179658496 10592731136 1260833184 13.338 97 20 37 1 1270822208 5122064 4866960
17:31:29 24216 8192000000 10104131776 10503614464 1149162288 12.302 92 30 61 1 1162304784 6213664 6928832
17:32:53 5275 8192000000 10133401936 10535067648 1235958608 13.109 99 31 62 0 1249932704 6700544 7273552
17:33:59 10167 8192000000 10159377216 10565849088 1268990368 13.412 100 24 47 2 1281752928 6234192 6528368
17:35:10 73544 8192000000 10172919168 10587893760 1272198352 13.442 99 29 57 1 1284018304 5177888 6642064
17:37:24 8314 8192000000 10189297184 10601086976 1294303472 13.643 100 26 53 1 1306264208 5588256 6372480
17:38:39 11359 8192000000 10241144144 10641264640 1328742048 13.956 100 50 101 1 1347190784 6265472 12183264
17:39:57 8597 8192000000 10257863472 10665062400 1347068448 14.121 100 55 110 0 1369472656 9614240 12789968
17:41:13 32891 8192000000 10261269680 10683461632 1362273824 14.258 100 39 78 2 1381507136 9813920 9419392
17:43:04 21558 8192000000 10275314896 10696867840 1367821104 14.308 100 36 72 2 1388070512 11001216 9248192
17:44:33 5325 8192000000 10293178848 10711044096 1375439840 14.376 100 59 117 0 1400126624 11224208 13462576
17:45:43 26449 8192000000 10310006032 10732380160 1390165328 14.507 100 57 114 2 1421654496 18106368 13382800
17:47:17 13903 8192000000 10318405568 10745864192 1405946032 14.648 100 48 96 1 1431631312 13726400 11958880
17:48:38 8416 8192000000 10322206160 10751635456 1396973184 14.568 99 43 84 1 1427416752 21067936 9375632
17:49:59 2248 8192000000 10337023280 10753478656 1399148608 14.587 99 84 166 4 1440801584 21004128 20648848
17:51:06 5280 8192000000 10343808960 10766376960 1409166592 14.677 100 59 118 1 1446666960 22760144 14740224
17:52:22 2455 8192000000 10340216512 10756517888 1397392768 14.572 99 63 126 0 1439904368 27407824 15103776
17:53:33 11839 8192000000 10343886928 10771398656 1409161728 14.676 100 54 106 0 1455389520 32080896 14146896
17:54:55 3200 8192000000 10354020976 10769862656 1404769600 14.637 100 63 126 0 1456515424 36604688 15141136
17:56:02 4733 8192000000 10314666784 10731671552 1365413760 14.286 99 60 119 2 1408716128 28937632 14364736
17:57:13 10108 8192000000 10347472560 10760019968 1395055760 14.551 100 73 146 0 1449116800 35270688 18790352
17:58:27 11831 8192000000 10315688144 10744225792 1382551424 14.439 100 40 80 3 1424213904 31375776 10286704
17:59:47 4549 8192000000 10317844720 10735443968 1363311296 14.267 99 51 101 1 1411597616 35339360 12946960
18:00:59 8257 8192000000 10306826944 10724794368 1352015040 14.166 100 47 94 1 1413372560 48715616 12641904
18:02:15 9044 8192000000 10324295424 10743394304 1365103232 14.283 100 44 88 1 1434491088 56429408 12958448
18:03:27 40566 8192000000 10356159200 10774671360 1390346128 14.509 100 48 94 1 1451841952 48104560 13391264
18:05:14 21465 8192000000 10335975056 10770853888 1384830128 14.460 100 26 52 0 1441675328 49586368 7258832
18:06:55 38105 8192000000 10327055456 10765643776 1355931232 14.201 99 23 46 2 1425853648 63147376 6775040
18:08:38 27550 8192000000 10326175824 10757906432 1354410416 14.187 99 32 60 2 1421814320 59247808 8156096
18:10:09 36449 8192000000 10259981632 10704621568 1327251808 13.942 98 18 35 0 1375271312 43187312 4832192
18:11:47 70528 8192000000 10278379632 10720378880 1361250576 14.249 100 18 36 1 1377802960 12990288 3562096
18:13:58 81994 8192000000 10274199536 10719780864 1337477296 14.035 98 5 10 1 1352315120 13857920 979904
18:16:20 43551 8192000000 10286773360 10725339136 1359781008 14.235 99 51 103 1 1382054576 11380624 10892944
18:18:14 123386 8192000000 10286212128 10750222336 1376277072 14.383 100 6 12 0 1394873312 17164064 1432176
18:21:28 62426 8192000000 10285113456 10732150784 1356029760 14.202 98 24 48 1 1371057824 9834240 5193824
18:23:32 83601 8192000000 10284750080 10752614400 1365527744 14.287 99 15 30 1 1395031280 25728000 3775536
18:26:07 125372 8192000000 10286168768 10742325248 1379888448 14.416 100 20 40 0 1405389920 20519824 4981648
18:29:17 71754 8192000000 10284833696 10751905792 1392854912 14.531 100 16 32 1 1406601840 10163520 3583408
18:31:30 14261 8192000000 10301468032 10754543616 1399691168 14.592 100 37 73 1 1415374784 7978048 7705568
18:32:52 24681 8192000000 10314691328 10765881344 1407474096 14.661 100 31 62 1 1423521152 8833856 7213200
18:34:19 26643 8192000000 10306967664 10762244096 1394375488 14.545 99 27 54 2 1411964096 10629680 6958928
18:35:54 41113 8192000000 10305752752 10779246592 1404330208 14.634 100 7 14 0 1423388224 17456800 1601216
18:37:43 73319 8192000000 10291648528 10758180864 1378178464 14.400 98 10 20 1 1388609840 8288800 2142576
18:40:22 75802 8192000000 10299455248 10781806592 1390548016 14.511 99 11 22 0 1406728512 13908144 2272352
18:42:39 45884 8192000000 10284437648 10756624384 1365879120 14.290 97 14 28 3 1379237568 10944448 2414000
18:44:26 70535 8192000000 10310329072 10764210176 1387394912 14.483 99 33 66 1 1405577776 10268096 7914768
18:46:38 33020 8192000000 10312464992 10764881920 1394975664 14.550 100 41 82 0 1421243040 16981056 9286320
18:48:12 115163 8192000000 10286045056 10728591360 1234142736 13.092 89 30 57 2 1253265264 12674912 6447616
18:51:21 15142 8192000000 10286854384 10733989888 1369675552 14.324 100 41 84 1 1392388848 13722528 8990768
18:52:37 105030 8192000000 10276953456 10721255424 1267681664 13.400 93 18 36 0 1288320272 16881520 3757088
18:55:23 86257 8192000000 10244203648 10684809216 1311188016 13.797 97 12 24 0 1323075712 9015712 2871984
18:57:57 61976 8192000000 10235439936 10681229312 1292700160 13.629 96 27 53 0 1310862432 12171328 5990944
19:00:50 64058 8192000000 10251888544 10711867392 1342139424 14.077 99 8 16 1 1354922720 10713360 2069936
19:02:54 64993 8192000000 10238119728 10695995392 1310373312 13.789 97 7 14 0 1324238928 12234720 1630896
19:05:04 6920 8192000000 10235486800 10689609728 1337345632 14.033 100 25 50 0 1352822880 10085808 5391440
19:06:11 63318 8192000000 10243802560 10691284992 1332911776 13.993 99 14 28 1 1343562352 7967392 2683184
19:08:21 60743 8192000000 10246685296 10699132928 1300781728 13.702 95 14 27 2 1313773392 10289568 2702096
19:10:28 117040 8192000000 10281951296 10743668736 1346788000 14.119 98 11 22 0 1359113344 10348560 1976784
19:13:25 40495 8192000000 10283851888 10736173056 1364015472 14.273 99 17 34 0 1385174448 17365776 3793200
19:15:06 86738 8192000000 10297329184 10752966656 1380791232 14.424 100 10 20 0 1400753952 17840864 2121856
19:17:33 93587 8192000000 10308923168 10741968896 1349182176 14.140 97 22 44 1 1367925072 12923504 5819392
19:20:07 141469 8192000000 10305786448 10761768960 1398779072 14.584 100 18 36 0 1416492944 13618272 4095600
19:23:54 22129 8192000000 10298656704 10768228352 1386962336 14.479 99 9 18 0 1407282928 18344496 1976096
19:25:17 52693 8192000000 10295069520 10758139904 1401531520 14.609 100 8 16 0 1411050944 7606448 1912976
19:27:10 62612 8192000000 10297114032 10759471104 1379217472 14.410 98 16 32 0 1393131584 9552240 4361872
19:29:18 77889 8192000000 10290424768 10751930368 1343569392 14.090 96 13 26 1 1359992240 13307136 3115712
19:31:39 46066 8192000000 10312919152 10757693440 1346758544 14.118 96 35 67 2 1367634368 13127504 7748320
19:33:27 73887 8192000000 10302940944 10758623232 1373628336 14.360 98 19 38 2 1395930928 17538304 4764288
19:35:53 25828 8192000000 10286200864 10739613696 1365823856 14.290 98 16 32 0 1382030656 12495568 3711232
19:37:19 42691 8192000000 10264781680 10701897728 1281606960 13.528 93 21 43 0 1294857312 9031200 4219152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-04-2022 19:39:21 to 15-04-2022 19:39:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-04-2022 19:39:36 to 15-04-2022 19:39:38.

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          234465
MON$OLDEST_ACTIVE               236804
MON$OLDEST_SNAPSHOT             236804
MON$NEXT_TRANSACTION            236804
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                       2041952
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:459: 
:460: mscs-test-161.budzdorov.ru    Fri Apr 15 19:38:14 2022
:461:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:462: 
:463: 
:464: mscs-test-161.budzdorov.ru    Fri Apr 15 19:38:20 2022
:465:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:466: 
:467: 
:468: mscs-test-161.budzdorov.ru    Fri Apr 15 19:38:20 2022
:469:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:470: 
--- end of diff output ---

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

15.04.22 19:39:39. 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
2022-04-15 19:39:42 No severe PSQL-related problems occured
15.04.22 19:39:43. End of report.