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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16629176 kB
MemAvailable 31571664 kB
Buffers 0 kB
Cached 15117504 kB
SwapCached 2512 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-23 22:38:15
DTS_END                         2022-04-23 22:38:15.7660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52900
TRN_ID                          230629
ATT_ID                          660
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: 9370.33 21765 1124439
customer order: creation 162.07 395 19448
customer order: refuse 13.81 310 1657
order to supplier: creation 42.98 1318 5157
order to supplier: removal 3.30 109446 396
invoice (draft): creation 43.08 3376 5170
invoice (draft): removal 56.12 747 6734
invoice accept: apply 39.44 19716 4733
invoice accept: cancel 51.15 843 6138
customer reserve: creation 3654.11 195 438493
customer reserve: removal 5011.89 57 601427
realization accept: apply 78.43 194 9411
realization accept: cancel 92.38 100 11085
payment from customer: creation 54.99 144 6599
payment from customer: removal 5.23 62 627
payment to supplier: creation 46.72 144 5606
payment to supplier: removal 3.38 94 405
service: total inventory turnovers 4.24 1293 509
service: total monetary turnovers 6.19 31 743
service: aggregate perf. data 0.81 21725 97
service: refresh index statistics 0.03 275101 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-04-2022 22:40:09 to 23-04-2022 22:40:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3036
TEST_TIME 2 2786
TEST_TIME 3 3778
TEST_TIME 4 4726
TEST_TIME 5 5279
TEST_TIME 6 6045
TEST_TIME 7 5838
TEST_TIME 8 5977
TEST_TIME 9 5962
TEST_TIME 10 5842
TEST_TIME 11 5774
TEST_TIME 12 6256
TEST_TIME 13 6260
TEST_TIME 14 6383
TEST_TIME 15 6438
TEST_TIME 16 6730
TEST_TIME 17 7282
TEST_TIME 18 7902
TEST_TIME 19 8659
TEST_TIME 20 8594
TEST_TIME 21 8693
TEST_TIME 22 8618
TEST_TIME 23 8759
TEST_TIME 24 9384
TEST_TIME 25 9412
TEST_TIME 26 9511
TEST_TIME 27 9616
TEST_TIME 28 10060
TEST_TIME 29 10161
TEST_TIME 30 10612
TEST_TIME 31 10743
TEST_TIME 32 11225
TEST_TIME 33 10927
TEST_TIME 34 10674
TEST_TIME 35 10563
TEST_TIME 36 10489
TEST_TIME 37 10348
TEST_TIME 38 10215
TEST_TIME 39 10084
TEST_TIME 40 9990
TEST_TIME 41 10177
TEST_TIME 42 10041
TEST_TIME 43 9986
TEST_TIME 44 9861
TEST_TIME 45 9946
TEST_TIME 46 9797
TEST_TIME 47 9741
TEST_TIME 48 9619
TEST_TIME 49 9614
TEST_TIME 50 9553
TEST_TIME 51 9440
TEST_TIME 52 9371
TEST_TIME 53 9504
TEST_TIME 54 9535
TEST_TIME 55 9457
TEST_TIME 56 9561
TEST_TIME 57 9609
TEST_TIME 58 9535
TEST_TIME 59 9614
TEST_TIME 60 9627
TEST_TIME 61 9716
TEST_TIME 62 9810
TEST_TIME 63 9768
TEST_TIME 64 9710
TEST_TIME 65 9849
TEST_TIME 66 9885
TEST_TIME 67 9804
TEST_TIME 68 9773
TEST_TIME 69 9681
TEST_TIME 70 9671
TEST_TIME 71 9607
TEST_TIME 72 9601
TEST_TIME 73 9542
TEST_TIME 74 9540
TEST_TIME 75 9523
TEST_TIME 76 9478
TEST_TIME 77 9407
TEST_TIME 78 9470
TEST_TIME 79 9408
TEST_TIME 80 9372
TEST_TIME 81 9392
TEST_TIME 82 9403
TEST_TIME 83 9395
TEST_TIME 84 9424
TEST_TIME 85 9475
TEST_TIME 86 9498
TEST_TIME 87 9458
TEST_TIME 88 9583
TEST_TIME 89 9520
TEST_TIME 90 9599
TEST_TIME 91 9563
TEST_TIME 92 9627
TEST_TIME 93 9617
TEST_TIME 94 9614
TEST_TIME 95 9537
TEST_TIME 96 9476
TEST_TIME 97 9459
TEST_TIME 98 9412
TEST_TIME 99 9403
TEST_TIME 100 9371
TEST_TIME 101 9330
TEST_TIME 102 9322
TEST_TIME 103 9278
TEST_TIME 104 9251
TEST_TIME 105 9193
TEST_TIME 106 9217
TEST_TIME 107 9197
TEST_TIME 108 9195
TEST_TIME 109 9223
TEST_TIME 110 9236
TEST_TIME 111 9229
TEST_TIME 112 9236
TEST_TIME 113 9212
TEST_TIME 114 9210
TEST_TIME 115 9269
TEST_TIME 116 9367
TEST_TIME 117 9395
TEST_TIME 118 9375
TEST_TIME 119 9378
TEST_TIME 120 9371

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-04-2022 22:40:13 to 23-04-2022 22:40:16.

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 1531459 1531459 0 0.00 0 32783 7 0 0
doc_list_biud 1531448 1531448 0 0.00 0 27935 28 0 0
sp_add_invoice_to_stock 4827 4733 94 1.94 39 191181 19716 0 94
sp_cancel_adding_invoice 6138 6138 0 0.00 4 74361 844 0 0
sp_cancel_client_order 1657 1657 0 0.00 4 7546 310 0 0
sp_cancel_customer_reserve 601427 601427 0 0.00 1 26232 58 0 0
sp_cancel_pay_from_customer 627 627 0 0.00 2 2987 63 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 2 7409 94 0 0
sp_cancel_supplier_invoice 6746 6734 12 0.17 1 16030 748 0 12
sp_cancel_supplier_order 396 396 0 0.00 6 811294 109447 0 0
sp_cancel_write_off 11085 11085 0 0.00 1 14212 100 0 0
sp_client_order 19448 19448 0 0.00 3 27340 395 0 0
sp_customer_reserve 438493 438493 0 0.00 0 44497 195 0 0
sp_fill_shopping_cart 29774 29774 0 0.00 1 14377 40 0 0
sp_fill_shopping_cart_clo_res 438493 438493 0 0.00 0 16031 15 0 0
sp_get_clo_for_invoice 4731 4731 0 0.00 0 19160 330 0 0
sp_kill_cost_storno 18255 18255 0 0.00 0 10653 55 0 0
sp_kill_qstorno_ret_qs2qd 1221069 1221069 0 0.00 0 26227 31 0 0
sp_kill_qty_storno 641568 641568 0 0.00 0 27935 67 0 0
sp_lock_selected_doc 19597 19597 0 0.00 0 6857 4 0 0
sp_make_cost_storno 26347 26347 0 0.00 0 22349 61 0 0
sp_multiply_rows_for_pdistr 26341 26341 0 0.00 0 10209 34 0 0
sp_multiply_rows_for_qdistr 19448 19448 0 0.00 0 12720 172 0 0
sp_pay_from_customer 6599 6599 0 0.00 4 12027 145 0 0
sp_pay_to_supplier 5606 5606 0 0.00 5 22369 144 0 0
sp_payment_common 12205 12205 0 0.00 1 13123 38 0 0
sp_qd_handle_on_cancel_clo 1657 1657 0 0.00 0 7541 284 0 0
sp_qd_handle_on_invoice_upd_sts 10864 10864 0 0.00 0 27933 339 0 0
sp_qd_handle_on_reserve_upd_sts 9405 9405 0 0.00 0 21373 73 0 0
sp_reserve_write_off 9411 9411 0 0.00 6 21399 194 0 0
sp_supplier_invoice 5170 5170 0 0.00 7 65477 3376 0 0
sp_supplier_order 5157 5157 0 0.00 5 38084 1318 0 0
srv_aggregate_perf_data 97 97 0 0.00 2644 354909 21725 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2708 110679 42603 0 0
srv_find_qd_qs_mism 1079890 1079890 0 0.00 0 32779 6 0 0
srv_make_invnt_saldo 509 509 0 0.00 14 34487 1293 0 0
srv_make_money_saldo 743 743 0 0.00 1 4519 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 78892 372445 275102 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4733 4733 0 0.00 0 14641 195 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 4812 104 0 0
t$perf-norm:sp_cancel_client_order 1657 1657 0 0.00 0 3820 13 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 2094 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 627 627 0 0.00 0 1421 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 913 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 770 12 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 24647 1846 0 0
t$perf-norm:sp_cancel_write_off 941 941 0 0.00 0 4414 8 0 0
t$perf-norm:sp_client_order 19448 19448 0 0.00 0 9207 17 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 926 4 0 0
t$perf-norm:sp_pay_from_customer 6599 6599 0 0.00 0 6014 12 0 0
t$perf-norm:sp_pay_to_supplier 5606 5606 0 0.00 0 28162 15 0 0
t$perf-norm:sp_reserve_write_off 9411 9411 0 0.00 0 9281 11 0 0
t$perf-norm:sp_supplier_invoice 5170 5170 0 0.00 0 8266 22 0 0
t$perf-norm:sp_supplier_order 5157 5157 0 0.00 0 2860 14 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 328 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1745 34 0 0
t$perf-norm:srv_make_invnt_saldo 509 509 0 0.00 0 2170 27 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 6576 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9632 9444 188 1.95 0 5928 22 0 188
v_all_customers 35678 35678 0 0.00 0 18 1 0 0
v_all_suppliers 28860 28860 0 0.00 0 23 1 0 0
v_all_wares 38896 38896 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 2816 13 0 0
v_cancel_client_order 3314 3314 0 0.00 0 4814 7 0 0
v_cancel_customer_prepayment 1254 1254 0 0.00 0 432 3 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 3135 6 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 2785 13 0 0
v_cancel_supplier_invoice 812 788 24 2.95 0 1741 17 0 24
v_cancel_supplier_order 792 792 0 0.00 0 1581 7 0 0
v_cancel_write_off 1882 1882 0 0.00 0 1302 11 0 0
v_max_id_clo_ord 5157 5157 0 0.00 0 823 2 0 0
v_max_id_clo_res 962 962 0 0.00 0 2492 12 0 0
v_max_id_ord_sup 5168 5168 0 0.00 0 5599 5 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 2542 6 0 0
v_max_non_paid_realizn 3339 3339 0 0.00 0 7238 9 0 0
v_min_id_clo_ord 5157 5157 0 0.00 0 11552 11 0 0
v_min_id_clo_res 962 962 0 0.00 0 3521 15 0 0
v_min_id_ord_sup 5168 5168 0 0.00 0 5505 7 0 0
v_min_non_paid_invoice 5606 5606 0 0.00 0 13112 28 0 0
v_min_non_paid_realizn 6599 6599 0 0.00 0 6543 30 0 0
v_our_firm 9738 9738 0 0.00 0 14 1 0 0
v_reserve_write_off 18810 18810 0 0.00 0 5075 8 0 0
x_lock_depdocs_on_canc_invoice 12478 12478 0 0.00 0 19858 112 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 6704 325 0 0
x_make_qstorno_1000_1200 5157 5157 0 0.00 1 38077 1229 0 0
x_make_qstorno_1200_2000 5170 5170 0 0.00 3 65452 3241 0 0
x_make_qstorno_2100_3300 438493 438493 0 0.00 0 44496 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-04-2022 22:40:17 to 23-04-2022 22:40:21.

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
20:08:19 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:19 31562 8192000000 9912933648 10224918528 991809088 10.799 100 87 174 0 1019517072 5661328 22046656
20:10:55 24872 8192000000 9961727216 10289733632 1046640496 11.328 100 81 162 1 1075315680 8362848 20312336
20:12:26 17229 8192000000 10004880288 10342539264 1081914416 11.666 100 87 174 0 1117235328 14223520 21097392
20:13:50 15684 8192000000 10053895760 10404261888 1136128224 12.179 100 83 166 0 1166924832 11415008 19381600
20:15:09 8719 8192000000 10098880368 10460512256 1178300464 12.574 100 77 153 1 1208329536 12769056 17260016
20:16:21 4995 8192000000 10140214336 10511974400 1222616624 12.986 100 86 172 1 1254709216 12674544 19418048
20:17:28 8280 8192000000 10170958816 10550206464 1259385952 13.324 100 84 168 0 1291832944 14187040 18259952
20:18:39 1448 8192000000 10210341840 10595401728 1291820352 13.621 100 92 180 2 1325485088 12883296 20781440
20:19:42 2157 8192000000 10224674080 10612490240 1308656272 13.774 100 92 185 2 1343384944 12805792 21922880
20:20:45 3251 8192000000 10208589248 10614611968 1313774560 13.820 100 47 92 1 1333142336 9341312 10026464
20:21:49 4029 8192000000 10204047792 10621853696 1322767072 13.902 100 41 82 1 1340596016 7666960 10161984
20:22:54 16333 8192000000 10207774736 10626846720 1323954672 13.912 100 27 54 0 1336697408 6572800 6169936
20:24:13 31776 8192000000 10221764784 10643718144 1326895072 13.939 100 39 80 1 1342067440 5419808 9752560
20:25:49 3577 8192000000 10236921312 10657345536 1339620768 14.054 100 48 97 1 1358907056 7579488 11706800
20:26:53 6022 8192000000 10230252512 10650193920 1335950912 14.021 100 22 44 1 1346330496 5184576 5195008
20:28:03 58867 8192000000 10201734336 10613071872 1223480816 12.994 92 30 61 0 1235554432 4671616 7402000
20:30:02 7065 8192000000 10154815584 10549837824 1215621616 12.921 94 37 74 1 1230230672 6005728 8603328
20:31:10 7642 8192000000 10159192096 10562949120 1227671904 13.033 96 25 49 2 1238916192 5887200 5357088
20:32:20 10039 8192000000 10123961440 10517884928 1170056176 12.497 93 22 43 1 1180956448 5343728 5556544
20:33:30 66762 8192000000 10149760576 10559168512 1251003408 13.247 99 24 46 0 1262020096 5372464 5644224
20:35:37 5791 8192000000 10194204496 10598731776 1285739232 13.565 100 32 65 1 1308042960 14810304 7493424
20:36:46 6474 8192000000 10193504128 10607976448 1302772400 13.720 100 22 44 2 1313702112 5037328 5892384
20:37:52 11232 8192000000 10216673712 10632019968 1317452608 13.854 100 20 40 1 1331863632 9741696 4669328
20:39:08 28114 8192000000 10252624992 10660384768 1344092016 14.094 100 39 77 1 1359588576 7437232 8059328
20:40:53 65473 8192000000 10270751440 10691166208 1354338880 14.186 100 32 62 0 1372348320 11136688 6872752
20:43:03 9510 8192000000 10294113280 10711126016 1374403472 14.366 100 60 120 0 1404136480 16721584 13011424
20:44:18 2707 8192000000 10309715280 10724937728 1385454912 14.465 100 54 108 0 1417360432 19291712 12613808
20:45:29 14030 8192000000 10305786784 10725146624 1375975024 14.381 99 54 108 0 1407824912 18803904 13045984
20:46:50 8722 8192000000 10318416336 10738921472 1394093520 14.542 100 55 110 2 1427823968 20754224 12976224
20:48:07 4262 8192000000 10333368528 10754830336 1404897632 14.639 100 61 123 0 1441949264 22196192 14855440
20:49:15 11726 8192000000 10347461680 10777681920 1417692752 14.752 100 47 94 0 1459253008 29953872 11606384
20:50:33 3339 8192000000 10376143568 10796613632 1428118800 14.845 100 76 152 1 1481961536 35240912 18601824
20:51:49 11131 8192000000 10359757056 10783293440 1421471040 14.786 100 56 109 1 1468012416 32824400 13716976
20:53:06 3273 8192000000 10360324336 10777878528 1412888112 14.710 100 67 132 2 1463636256 34651872 16096272
20:54:15 10025 8192000000 10358091360 10769518592 1371552528 14.341 98 75 150 1 1437574528 47112368 18909632
20:55:32 5255 8192000000 10300003744 10718244864 1338876416 14.047 98 52 104 1 1394544384 41479776 14188192
20:56:42 15300 8192000000 10332580400 10755096576 1379889488 14.416 100 51 99 1 1433847408 40892176 13065744
20:58:00 10917 8192000000 10300188480 10729930752 1351205280 14.158 98 29 58 0 1395345728 36078496 8061952
20:59:19 9544 8192000000 10313131216 10737508352 1365898816 14.290 100 29 58 2 1416206144 42360704 7946624
21:00:33 5982 8192000000 10317102624 10741657600 1360534336 14.242 99 37 72 0 1414953296 44698944 9720016
21:01:45 7717 8192000000 10348454256 10778218496 1392903760 14.532 100 46 92 3 1456230784 50179136 13147888
21:02:56 15783 8192000000 10330730720 10766454784 1377908160 14.398 100 27 54 2 1428627136 43409184 7309792
21:04:17 16016 8192000000 10319300320 10756976640 1380472720 14.421 100 31 62 1 1432143552 43259392 8411440
21:05:39 10594 8192000000 10341608128 10777722880 1384368384 14.456 100 42 84 1 1448981264 53029776 11583104
21:07:12 21543 8192000000 10318523792 10759065600 1359253568 14.231 99 20 40 2 1410940544 45784288 5902688
21:08:34 25883 8192000000 10294970656 10743377920 1346810240 14.119 99 15 31 0 1388576800 37743024 4023536
21:10:02 27371 8192000000 10296006848 10727833600 1355368528 14.196 99 52 103 2 1385456000 18405920 11681552
21:11:34 66508 8192000000 10276005632 10719993856 1365578224 14.287 100 44 89 0 1381579024 5892048 10108752
21:13:44 31774 8192000000 10270185584 10728411136 1346838896 14.119 98 12 24 2 1359103760 9543248 2721616
21:15:36 73774 8192000000 10282591696 10722287616 1328457344 13.953 97 32 64 0 1347867600 12154896 7255360
21:17:52 53217 8192000000 10283823888 10742353920 1375653440 14.378 100 12 24 0 1397133904 18198640 3281824
21:21:05 39205 8192000000 10285926688 10744221696 1384277824 14.455 100 16 32 0 1399378800 11384272 3716704
21:22:50 82265 8192000000 10280226464 10745380864 1386786624 14.477 100 12 24 0 1401718848 11855088 3077136
21:25:13 7779 8192000000 10290331840 10742239232 1383340000 14.446 100 39 79 0 1401378640 9756576 8282064
21:26:21 21941 8192000000 10290313472 10736128000 1358946288 14.228 98 46 90 3 1382209328 12089744 11173296
21:27:44 87421 8192000000 10299476576 10761437184 1385628512 14.467 100 14 28 0 1413759696 24827856 3303328
21:30:11 59180 8192000000 10288789600 10741129216 1375082160 14.373 99 32 64 1 1401335936 18507760 7746016
21:32:39 67768 8192000000 10300892224 10758217728 1376735360 14.387 99 24 49 0 1391281520 8978240 5567920
21:34:47 63536 8192000000 10296255776 10772803584 1380329776 14.419 99 10 20 0 1396978080 14395104 2253200
21:36:51 17713 8192000000 10299244928 10747863040 1369443696 14.322 98 54 108 1 1392394112 10764208 12186208
21:38:09 27246 8192000000 10293175040 10738167808 1365275104 14.285 99 52 104 1 1388231904 11313248 11643552
21:39:36 110679 8192000000 10278629280 10737205248 1336389200 14.025 96 9 18 0 1354857792 16661072 1807520
21:42:27 21877 8192000000 10307889184 10750275584 1361493392 14.251 98 37 74 0 1383293456 13658848 8141216
21:43:57 87575 8192000000 10262073200 10699423744 1293486880 13.636 94 9 18 0 1311228528 16198896 1542752
21:46:25 48145 8192000000 10288608032 10712203264 1336449408 14.025 98 46 92 0 1360037664 13803568 9784688
21:48:19 67200 8192000000 10282047648 10728939520 1360038144 14.238 99 32 62 1 1379423744 12298144 7087456
21:50:27 26755 8192000000 10269231360 10727198720 1357669200 14.216 99 12 24 1 1372351968 11563856 3118912
21:51:54 27423 8192000000 10270125760 10722435072 1347732768 14.127 98 24 48 0 1363208928 10205472 5270688
21:53:22 63869 8192000000 10245363760 10697555968 1313849808 13.821 97 7 14 0 1323466992 7692960 1924224
21:55:27 78638 8192000000 10241584256 10696630272 1328645840 13.955 98 22 44 1 1349673056 15173776 5853440
21:58:21 70132 8192000000 10251394480 10711248896 1327131680 13.941 99 17 34 0 1347231248 15971744 4127824
22:00:59 67491 8192000000 10245853440 10694017024 1301444320 13.708 97 12 24 1 1323576688 18972272 3160096
22:03:07 96153 8192000000 10257252736 10700627968 1300003904 13.695 96 23 46 0 1319992864 14660400 5328560
22:05:43 76534 8192000000 10267248624 10728538112 1359989888 14.237 100 21 42 2 1379793344 14755984 5047472
22:08:00 41129 8192000000 10285829168 10732912640 1363095824 14.265 100 27 53 2 1385023136 16163904 5763408
22:09:47 61019 8192000000 10275972960 10735493120 1347750928 14.127 98 16 30 1 1366458272 15378112 3329232
22:11:48 43833 8192000000 10298352480 10735370240 1373819520 14.361 99 24 48 1 1386967584 8151024 4997040
22:13:32 75778 8192000000 10291542752 10744102912 1388043408 14.488 100 29 56 2 1401172896 6961392 6168096
22:15:48 72181 8192000000 10288904336 10754760704 1389939280 14.505 100 7 14 1 1399672304 8443504 1289520
22:18:00 39326 8192000000 10289973504 10756243456 1393387376 14.536 100 10 20 0 1404577168 8936272 2253520
22:19:42 38464 8192000000 10299341056 10761117696 1403144704 14.623 100 21 41 1 1414946832 7995584 3806544
22:21:25 109146 8192000000 10308561712 10766012416 1387956832 14.488 99 20 41 0 1403313360 11015632 4340896
22:24:18 78638 8192000000 10296872896 10765754368 1374095152 14.364 98 11 22 1 1390351824 13685488 2571184
22:26:51 87605 8192000000 10290441920 10742751232 1337092928 14.031 95 16 32 1 1357162672 16317920 3751824
22:29:22 37258 8192000000 10321301632 10772361216 1393776336 14.540 99 22 44 0 1410905472 12201376 4927760
22:30:59 43462 8192000000 10322979040 10773327872 1401020688 14.604 100 20 37 1 1421477712 16265008 4192016
22:32:50 70910 8192000000 10304852416 10773381120 1371919792 14.344 97 19 38 1 1393549680 17110304 4519584
22:35:05 69773 8192000000 10296910832 10743398400 1315767456 13.838 94 18 36 0 1331890432 11052768 5070208
22:37:28 57139 8192000000 9142713136 9394192384 301207376 3.546 22 3 6 0 305966208 4302528 456304

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-04-2022 22:40:23 to 23-04-2022 22:40:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-04-2022 22:40:39 to 23-04-2022 22:40:41.

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          230894
MON$OLDEST_ACTIVE               230895
MON$OLDEST_SNAPSHOT             230895
MON$NEXT_TRANSACTION            230895
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                       2037664
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 ---
--- end of diff output ---

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

23.04.22 22:40:42. 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-23 22:40:45 No severe PSQL-related problems occured
23.04.22 22:40:46. End of report.