Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.05.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 16515160 kB
MemAvailable 31492448 kB
Buffers 0 kB
Cached 15165688 kB
SwapCached 3268 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-05-03 19:38:12
DTS_END                         2022-05-03 19:38:12.6920
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/34368
TRN_ID                          244937
ATT_ID                          722
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: 9173.83 20527 1100859
customer order: creation 177.59 330 21311
customer order: refuse 15.23 340 1828
order to supplier: creation 46.79 1291 5615
order to supplier: removal 3.48 86750 418
invoice (draft): creation 47.93 3520 5751
invoice (draft): removal 49.94 807 5993
invoice accept: apply 43.97 17718 5276
invoice accept: cancel 44.97 788 5396
customer reserve: creation 4009.40 178 481128
customer reserve: removal 4423.76 52 530851
realization accept: apply 87.65 179 10518
realization accept: cancel 86.28 106 10353
payment from customer: creation 62.53 98 7504
payment from customer: removal 5.94 68 713
payment to supplier: creation 51.28 130 6154
payment to supplier: removal 3.39 68 407
service: total inventory turnovers 5.12 1098 614
service: total monetary turnovers 7.52 36 902
service: aggregate perf. data 1.03 15646 123
service: refresh index statistics 0.03 281332 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-05-2022 19:39:02 to 03-05-2022 19:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7332
TEST_TIME 2 5819
TEST_TIME 3 5959
TEST_TIME 4 5175
TEST_TIME 5 5857
TEST_TIME 6 6046
TEST_TIME 7 6063
TEST_TIME 8 5959
TEST_TIME 9 6108
TEST_TIME 10 6156
TEST_TIME 11 6748
TEST_TIME 12 7028
TEST_TIME 13 7023
TEST_TIME 14 7303
TEST_TIME 15 7551
TEST_TIME 16 8375
TEST_TIME 17 8729
TEST_TIME 18 8720
TEST_TIME 19 8935
TEST_TIME 20 8962
TEST_TIME 21 8752
TEST_TIME 22 9148
TEST_TIME 23 9039
TEST_TIME 24 9021
TEST_TIME 25 9387
TEST_TIME 26 9414
TEST_TIME 27 10108
TEST_TIME 28 10153
TEST_TIME 29 10874
TEST_TIME 30 10822
TEST_TIME 31 10654
TEST_TIME 32 10693
TEST_TIME 33 10636
TEST_TIME 34 10561
TEST_TIME 35 10539
TEST_TIME 36 10422
TEST_TIME 37 10341
TEST_TIME 38 10223
TEST_TIME 39 10219
TEST_TIME 40 10158
TEST_TIME 41 10049
TEST_TIME 42 9963
TEST_TIME 43 9930
TEST_TIME 44 9896
TEST_TIME 45 9857
TEST_TIME 46 9804
TEST_TIME 47 9788
TEST_TIME 48 9671
TEST_TIME 49 9703
TEST_TIME 50 9599
TEST_TIME 51 9573
TEST_TIME 52 9623
TEST_TIME 53 9603
TEST_TIME 54 9530
TEST_TIME 55 9448
TEST_TIME 56 9381
TEST_TIME 57 9318
TEST_TIME 58 9236
TEST_TIME 59 9219
TEST_TIME 60 9180
TEST_TIME 61 9184
TEST_TIME 62 9213
TEST_TIME 63 9197
TEST_TIME 64 9160
TEST_TIME 65 9283
TEST_TIME 66 9272
TEST_TIME 67 9258
TEST_TIME 68 9391
TEST_TIME 69 9322
TEST_TIME 70 9407
TEST_TIME 71 9450
TEST_TIME 72 9410
TEST_TIME 73 9400
TEST_TIME 74 9431
TEST_TIME 75 9465
TEST_TIME 76 9363
TEST_TIME 77 9310
TEST_TIME 78 9372
TEST_TIME 79 9349
TEST_TIME 80 9307
TEST_TIME 81 9238
TEST_TIME 82 9189
TEST_TIME 83 9147
TEST_TIME 84 9093
TEST_TIME 85 9071
TEST_TIME 86 9096
TEST_TIME 87 9081
TEST_TIME 88 9116
TEST_TIME 89 9087
TEST_TIME 90 9217
TEST_TIME 91 9248
TEST_TIME 92 9214
TEST_TIME 93 9219
TEST_TIME 94 9215
TEST_TIME 95 9288
TEST_TIME 96 9350
TEST_TIME 97 9323
TEST_TIME 98 9301
TEST_TIME 99 9338
TEST_TIME 100 9346
TEST_TIME 101 9257
TEST_TIME 102 9198
TEST_TIME 103 9210
TEST_TIME 104 9185
TEST_TIME 105 9148
TEST_TIME 106 9138
TEST_TIME 107 9122
TEST_TIME 108 9082
TEST_TIME 109 9089
TEST_TIME 110 9076
TEST_TIME 111 9109
TEST_TIME 112 9132
TEST_TIME 113 9098
TEST_TIME 114 9098
TEST_TIME 115 9189
TEST_TIME 116 9173
TEST_TIME 117 9191
TEST_TIME 118 9196
TEST_TIME 119 9176
TEST_TIME 120 9177

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-05-2022 19:39:06 to 03-05-2022 19:39:09.

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 1544330 1544330 0 0.00 0 20479 6 0 0
doc_list_biud 1544323 1544323 0 0.00 0 26281 24 0 0
sp_add_invoice_to_stock 5388 5276 112 2.07 20 187981 17718 0 112
sp_cancel_adding_invoice 5396 5396 0 0.00 4 54056 789 0 0
sp_cancel_client_order 1828 1828 0 0.00 4 10520 341 0 0
sp_cancel_customer_reserve 530851 530851 0 0.00 1 26283 53 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 2 6079 69 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 2 4769 69 0 0
sp_cancel_supplier_invoice 5999 5993 6 0.10 1 15856 807 0 6
sp_cancel_supplier_order 418 418 0 0.00 6 1020401 86750 0 0
sp_cancel_write_off 10353 10353 0 0.00 1 14208 106 0 0
sp_client_order 21311 21311 0 0.00 3 19258 330 0 0
sp_customer_reserve 481128 481128 0 0.00 0 44235 178 0 0
sp_fill_shopping_cart 32664 32664 0 0.00 1 12948 42 0 0
sp_fill_shopping_cart_clo_res 481118 481118 0 0.00 0 12699 13 0 0
sp_get_clo_for_invoice 5266 5266 0 0.00 0 24081 294 0 0
sp_kill_cost_storno 16869 16869 0 0.00 0 6875 48 0 0
sp_kill_qstorno_ret_qs2qd 1078466 1078466 0 0.00 0 26277 29 0 0
sp_kill_qty_storno 570620 570620 0 0.00 0 26281 64 0 0
sp_lock_selected_doc 21703 21703 0 0.00 0 9624 7 0 0
sp_make_cost_storno 29442 29442 0 0.00 0 18974 51 0 0
sp_multiply_rows_for_pdistr 29437 29437 0 0.00 0 7696 28 0 0
sp_multiply_rows_for_qdistr 21311 21311 0 0.00 0 9503 149 0 0
sp_pay_from_customer 7504 7504 0 0.00 4 17909 99 0 0
sp_pay_to_supplier 6154 6154 0 0.00 5 18981 130 0 0
sp_payment_common 13656 13656 0 0.00 2 17897 26 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 10514 316 0 0
sp_qd_handle_on_invoice_upd_sts 10660 10660 0 0.00 0 18578 330 0 0
sp_qd_handle_on_reserve_upd_sts 10517 10517 0 0.00 0 24267 64 0 0
sp_reserve_write_off 10518 10518 0 0.00 6 24282 179 0 0
sp_supplier_invoice 5751 5751 0 0.00 5 89732 3520 0 0
sp_supplier_order 5615 5615 0 0.00 5 35478 1291 0 0
srv_aggregate_perf_data 123 123 0 0.00 2545 175876 15646 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 3325 151348 47461 0 0
srv_find_qd_qs_mism 1050092 1050092 0 0.00 0 20331 6 0 0
srv_make_invnt_saldo 614 614 0 0.00 33 156632 1099 0 0
srv_make_money_saldo 902 902 0 0.00 1 9907 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 171746 480081 281333 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5277 5277 0 0.00 0 23235 168 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 2769 72 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 6065 16 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 1019 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 532 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 833 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 978 18 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 27160 1469 0 0
t$perf-norm:sp_cancel_write_off 1104 1104 0 0.00 0 2666 6 0 0
t$perf-norm:sp_client_order 21311 21311 0 0.00 0 13022 18 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 1770 4 0 0
t$perf-norm:sp_pay_from_customer 7504 7504 0 0.00 0 8405 10 0 0
t$perf-norm:sp_pay_to_supplier 6154 6154 0 0.00 0 7408 8 0 0
t$perf-norm:sp_reserve_write_off 10518 10518 0 0.00 0 8772 12 0 0
t$perf-norm:sp_supplier_invoice 5751 5751 0 0.00 0 19433 23 0 0
t$perf-norm:sp_supplier_order 5615 5615 0 0.00 0 4991 14 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 173 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 20 1 0 0
t$perf-norm:srv_make_invnt_saldo 614 614 0 0.00 0 1020 18 0 0
t$perf-norm:srv_make_money_saldo 902 902 0 0.00 0 681 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10752 10528 224 2.08 0 10768 20 0 224
v_all_customers 38784 38784 0 0.00 0 18 1 0 0
v_all_suppliers 31398 31398 0 0.00 0 47 1 0 0
v_all_wares 42622 42622 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 4776 12 0 0
v_cancel_client_order 3656 3656 0 0.00 0 3512 5 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 1395 3 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 1262 4 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 2389 6 0 0
v_cancel_supplier_invoice 766 754 12 1.56 0 4549 19 0 12
v_cancel_supplier_order 836 836 0 0.00 0 1954 5 0 0
v_cancel_write_off 2208 2208 0 0.00 0 3080 16 0 0
v_max_id_clo_ord 5612 5612 0 0.00 0 346 1 0 0
v_max_id_clo_res 959 959 0 0.00 0 2991 16 0 0
v_max_id_ord_sup 5741 5741 0 0.00 0 3252 4 0 0
v_max_non_paid_invoice 1808 1808 0 0.00 0 375 2 0 0
v_max_non_paid_realizn 4018 4018 0 0.00 0 17873 8 0 0
v_min_id_clo_ord 5612 5612 0 0.00 0 3513 6 0 0
v_min_id_clo_res 959 959 0 0.00 0 5783 9 0 0
v_min_id_ord_sup 5741 5741 0 0.00 0 3483 5 0 0
v_min_non_paid_invoice 6154 6154 0 0.00 0 12421 21 0 0
v_min_non_paid_realizn 7502 7502 0 0.00 0 6923 17 0 0
v_our_firm 10806 10806 0 0.00 0 97 1 0 0
v_reserve_write_off 21034 21034 0 0.00 0 4791 6 0 0
x_lock_depdocs_on_canc_invoice 11012 11012 0 0.00 0 14183 117 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 7697 350 0 0
x_make_qstorno_1000_1200 5615 5615 0 0.00 1 35472 1203 0 0
x_make_qstorno_1200_2000 5751 5751 0 0.00 2 89607 3375 0 0
x_make_qstorno_2100_3300 481128 481128 0 0.00 0 44234 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-05-2022 19:39:10 to 03-05-2022 19:39:14.

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:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:16 32808 8192000000 9910080832 10220027904 988116192 10.763 100 92 184 0 1018405760 5903840 24385728
17:10:57 25319 8192000000 9966411376 10291507200 1045337184 11.316 100 94 188 0 1077611472 8432416 23841872
17:12:28 18968 8192000000 9998068496 10332631040 1079100000 11.639 100 80 160 1 1108555456 9812544 19642912
17:13:50 13466 8192000000 10046734176 10398957568 1136893920 12.186 100 81 161 2 1167498752 10954080 19650752
17:15:06 8325 8192000000 10095966672 10456440832 1185799648 12.644 100 74 148 0 1214638848 12013664 16825536
17:16:17 5257 8192000000 10138477536 10509160448 1226647600 13.023 100 77 155 1 1256143584 12123568 17372416
17:17:25 4913 8192000000 10163403184 10544459776 1254173760 13.277 100 75 149 1 1283538992 12812032 16553200
17:18:31 1859 8192000000 10204203440 10589753344 1294725872 13.647 100 91 180 1 1328408704 13652752 20030080
17:19:35 1090 8192000000 10224546672 10610323456 1308957312 13.777 100 81 160 1 1339664176 11998320 18708544
17:20:37 3616 8192000000 10211336464 10614415360 1313749776 13.820 100 51 104 1 1335184448 9553056 11881616
17:21:41 22031 8192000000 10207726960 10624446464 1319478288 13.872 100 28 56 0 1331160288 5112992 6569008
17:23:06 6240 8192000000 10216381792 10638405632 1330019696 13.967 100 28 56 1 1343453968 7372000 6062272
17:24:14 5948 8192000000 10225695856 10646482944 1334876352 14.011 100 27 54 2 1347551552 6924496 5750704
17:25:21 17556 8192000000 10229188944 10649726976 1338141792 14.041 100 21 43 0 1348344736 5509280 4693664
17:26:39 22109 8192000000 10232989392 10652839936 1326558112 13.936 99 24 48 0 1339163248 7633936 4971200
17:28:02 3502 8192000000 10228481216 10647666688 1336372528 14.025 100 25 51 0 1348538304 6727152 5438624
17:29:08 5530 8192000000 10192475392 10606862336 1281983952 13.531 98 29 58 1 1295914160 6015840 7914368
17:30:19 32471 8192000000 10157030352 10567397376 1192670064 12.708 92 27 53 3 1204309664 5111248 6528352
17:32:24 8246 8192000000 10140246624 10535636992 1229311584 13.048 98 15 30 1 1237570912 4585296 3674032
17:33:35 6372 8192000000 10163486448 10567544832 1266432816 13.389 100 30 60 2 1280720032 6907536 7379680
17:34:44 18839 8192000000 10184793344 10588712960 1280347728 13.516 100 20 40 0 1291014976 5535584 5131664
17:36:03 32796 8192000000 10168417728 10587734016 1284759456 13.556 100 20 40 0 1294729200 5198736 4771008
17:37:38 8233 8192000000 10190356896 10604298240 1293323840 13.634 100 22 42 0 1304244176 4993824 5926512
17:38:51 10842 8192000000 10227131520 10637201408 1324788656 13.920 100 36 72 2 1340123776 7393520 7941600
17:40:07 29718 8192000000 10243794544 10661089280 1339699392 14.055 100 39 78 1 1358653728 9793456 9160880
17:42:04 12118 8192000000 10265533248 10679590912 1354533696 14.188 100 55 107 3 1380910416 13809568 12567152
17:43:21 17120 8192000000 10277787360 10694631424 1365055056 14.283 100 60 120 2 1396225488 17101888 14068544
17:44:44 13030 8192000000 10285160944 10702569472 1359493376 14.233 99 54 108 1 1392737168 20730208 12513584
17:46:01 12675 8192000000 10304131696 10719010816 1370595216 14.332 99 60 120 2 1403275232 18702336 13977680
17:47:19 12409 8192000000 10315985920 10737172480 1391111824 14.516 100 56 110 4 1425833216 22325056 12396336
17:48:37 9668 8192000000 10324531552 10737508352 1388958688 14.497 100 71 142 0 1428213632 22189216 17065728
17:49:48 3776 8192000000 10311030992 10721951744 1370172608 14.329 99 78 154 2 1411632864 23032736 18427520
17:50:56 14276 8192000000 10324341696 10739011584 1388022672 14.488 100 72 144 0 1433494064 27471728 17999664
17:52:21 6527 8192000000 10332061952 10748223488 1379479936 14.412 99 66 129 2 1427826448 31977024 16369488
17:53:32 7221 8192000000 10328460880 10745602048 1380463424 14.421 100 78 157 0 1438567472 38874256 19229792
17:54:46 3322 8192000000 10319373680 10730639360 1365584272 14.287 100 78 153 0 1420709424 35993088 19132064
17:55:52 4128 8192000000 10298241616 10720800768 1364965328 14.282 100 54 108 0 1408393216 29933168 13494720
17:57:03 5829 8192000000 10297874992 10715602944 1358321200 14.222 100 61 121 5 1402419264 29776032 14322032
17:58:11 7237 8192000000 10288738016 10712379392 1355316576 14.195 100 29 58 2 1391535136 28579888 7638672
17:59:31 4648 8192000000 10293700576 10704916480 1332794048 13.992 98 71 139 1 1381450592 30989360 17667184
18:00:39 10906 8192000000 10309630816 10743001088 1371917136 14.344 100 27 54 2 1415854000 36477696 7459168
18:02:03 8040 8192000000 10304933472 10717990912 1353032480 14.175 99 44 87 3 1409667120 44820768 11813872
18:03:17 23817 8192000000 10289883152 10718060544 1343643920 14.090 99 30 60 0 1402263440 49860480 8759040
18:04:46 20911 8192000000 10310727792 10738790400 1357444224 14.214 100 24 47 4 1411623008 47069328 7109456
18:06:12 47395 8192000000 10312155424 10746515456 1345670592 14.109 99 20 40 0 1395734896 43638784 6425520
18:08:04 98494 8192000000 10282540512 10722533376 1362188080 14.257 100 17 34 0 1385076320 19117328 3770912
18:10:53 21977 8192000000 10273577600 10707546112 1360227072 14.239 99 32 64 2 1375239920 7984400 7028448
18:12:16 31223 8192000000 10287846240 10741563392 1382808176 14.442 100 23 46 0 1396783760 8899152 5076432
18:13:48 46224 8192000000 10286522672 10739081216 1383754896 14.450 100 10 20 0 1396598192 10630832 2212464
18:15:35 48773 8192000000 10298286064 10748522496 1393801840 14.540 100 21 42 0 1408273616 9443760 5028016
18:17:27 54980 8192000000 10303311568 10750033920 1388311776 14.491 99 46 92 1 1407699056 7648080 11739200
18:19:31 36304 8192000000 10318478112 10757353472 1390338128 14.509 99 47 96 1 1410712928 8421568 11953232
18:21:19 108566 8192000000 10296451872 10750902272 1376770800 14.388 99 19 38 1 1393928112 12399344 4757968
18:24:14 71769 8192000000 10290467584 10744496128 1368601360 14.315 98 22 44 0 1383155392 10181248 4372784
18:28:17 20786 8192000000 10316413680 10762575872 1402522032 14.617 100 29 58 1 1419455344 10254512 6678800
18:29:38 76358 8192000000 10302848304 10773389312 1389574320 14.502 99 13 26 0 1411374928 18777632 3022976
18:31:59 74569 8192000000 10270380224 10733506560 1361168528 14.248 97 7 14 0 1369354016 6583024 1602464
18:34:16 64723 8192000000 10254098064 10713145344 1336124432 14.022 96 16 31 1 1347080912 7415520 3540960
18:36:26 79977 8192000000 10260866384 10734100480 1370618688 14.333 99 9 18 0 1380896560 8229872 2048000
18:38:48 62385 8192000000 10240047536 10696695808 1298969296 13.686 94 9 18 0 1307917520 6765248 2182976
18:40:59 53482 8192000000 10271489392 10726592512 1337251248 14.033 97 15 29 1 1354465104 13950640 3263216
18:42:54 38209 8192000000 10264326816 10716942336 1338856800 14.047 98 27 54 1 1357907680 12865456 6185424
18:44:43 57862 8192000000 10267786848 10712805376 1325043360 13.922 97 33 66 0 1344697232 12574592 7079280
18:46:49 83586 8192000000 10277233936 10715348992 1305657280 13.747 95 21 41 0 1326502544 16188720 4656544
18:49:20 63009 8192000000 10266271712 10717552640 1327609584 13.946 97 17 34 0 1344125568 12710672 3805312
18:51:23 29959 8192000000 10266382928 10700460032 1326996496 13.940 98 34 66 1 1342247168 7932000 7318672
18:52:55 80158 8192000000 10264102080 10720485376 1310803536 13.793 96 23 46 1 1330480400 14268720 5408144
18:55:15 79866 8192000000 10251750512 10717859840 1333430528 13.998 98 28 56 2 1355140032 14779248 6930256
18:57:39 34143 8192000000 10246135008 10703319040 1339166464 14.050 99 16 32 1 1356184208 12900816 4116928
18:59:15 68858 8192000000 10255484496 10712764416 1339518944 14.053 98 11 22 1 1350425872 8408128 2498800
19:01:24 41779 8192000000 10266771440 10723823616 1371593088 14.341 100 12 24 0 1385486992 10968736 2925168
19:03:07 72333 8192000000 10253418560 10718978048 1327154464 13.941 97 11 22 0 1346040048 16380192 2505392
19:05:22 57774 8192000000 10262867904 10723704832 1332942448 13.994 97 17 34 0 1347748512 10653760 4152304
19:07:20 40933 8192000000 10301535232 10751541248 1380751072 14.423 100 27 54 0 1404036096 17256784 6028240
19:09:13 94945 8192000000 10304187792 10758213632 1395533232 14.555 100 11 22 0 1407103744 9794416 1776096
19:11:53 32526 8192000000 10325806720 10772385792 1390726624 14.512 99 28 56 0 1405688224 9013056 5948544
19:13:29 151348 8192000000 10299390800 10758504448 1343224592 14.086 96 9 18 1 1357470704 12335648 1910464
19:17:05 50416 8192000000 10305700080 10765299712 1381249120 14.428 99 34 69 2 1404740560 16093264 7398176
19:18:56 143963 8192000000 10276061504 10725593088 1318268848 13.861 95 18 36 0 1330320384 8210688 3840848
19:22:43 124809 8192000000 10281648064 10739511296 1322225024 13.897 95 7 14 1 1337443552 13563808 1654720
19:26:48 58379 8192000000 10262932096 10719690752 1309808368 13.784 95 15 31 0 1320675136 7370160 3496608
19:28:50 68769 8192000000 10266892064 10725974016 1332194720 13.987 97 13 24 0 1347797664 13061488 2541456
19:31:09 34670 8192000000 10271658880 10719866880 1332550560 13.990 97 23 46 0 1347464016 9708176 5205280
19:32:54 90824 8192000000 10269020944 10720165888 1305109776 13.742 95 7 14 1 1325080656 17849184 2121696
19:35:25 107129 8192000000 10268252336 10717003776 1326154224 13.932 97 14 28 0 1340291216 11106640 3030352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-05-2022 19:39:16 to 03-05-2022 19:39:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-05-2022 19:39:31 to 03-05-2022 19:39:33.

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          243544
MON$OLDEST_ACTIVE               245214
MON$OLDEST_SNAPSHOT             245214
MON$NEXT_TRANSACTION            245214
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                       2039456
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.33585 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33585 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33585 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33585 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue May  3 19:38:19 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
--- end of diff output ---

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

03.05.22 19:39:34. 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-05-03 19:39:37 No severe PSQL-related problems occured
03.05.22 19:39:38. End of report.