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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16130024 kB
MemAvailable 31173672 kB
Buffers 0 kB
Cached 15615796 kB
SwapCached 2544 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.11
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-07-06 16:38:25
DTS_END                         2022-07-06 16:38:25.2940
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/48608
TRN_ID                          247115
ATT_ID                          659
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: 9450.00 19996 1134000
customer order: creation 178.23 316 21388
customer order: refuse 15.73 328 1887
order to supplier: creation 47.47 1125 5696
order to supplier: removal 3.32 93283 398
invoice (draft): creation 47.85 2846 5742
invoice (draft): removal 53.65 691 6438
invoice accept: apply 42.98 17625 5157
invoice accept: cancel 48.07 744 5768
customer reserve: creation 4015.03 174 481804
customer reserve: removal 4696.17 52 563540
realization accept: apply 86.21 193 10345
realization accept: cancel 81.88 111 9825
payment from customer: creation 61.38 134 7365
payment from customer: removal 5.28 54 634
payment to supplier: creation 50.37 141 6044
payment to supplier: removal 3.46 55 415
service: total inventory turnovers 5.02 863 602
service: total monetary turnovers 7.05 17 846
service: aggregate perf. data 0.85 22369 102
service: refresh index statistics 0.03 258804 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-07-2022 16:40:34 to 06-07-2022 16:40:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4530
TEST_TIME 2 3038
TEST_TIME 3 4899
TEST_TIME 4 4931
TEST_TIME 5 5729
TEST_TIME 6 5696
TEST_TIME 7 5550
TEST_TIME 8 5576
TEST_TIME 9 5486
TEST_TIME 10 5832
TEST_TIME 11 5907
TEST_TIME 12 5878
TEST_TIME 13 6112
TEST_TIME 14 6129
TEST_TIME 15 6067
TEST_TIME 16 6096
TEST_TIME 17 6475
TEST_TIME 18 6459
TEST_TIME 19 6503
TEST_TIME 20 6599
TEST_TIME 21 7259
TEST_TIME 22 7692
TEST_TIME 23 8056
TEST_TIME 24 8323
TEST_TIME 25 8685
TEST_TIME 26 8940
TEST_TIME 27 9386
TEST_TIME 28 10155
TEST_TIME 29 10162
TEST_TIME 30 10160
TEST_TIME 31 10208
TEST_TIME 32 10195
TEST_TIME 33 10064
TEST_TIME 34 10133
TEST_TIME 35 10063
TEST_TIME 36 10199
TEST_TIME 37 10329
TEST_TIME 38 10205
TEST_TIME 39 10003
TEST_TIME 40 9802
TEST_TIME 41 9817
TEST_TIME 42 9858
TEST_TIME 43 9806
TEST_TIME 44 9751
TEST_TIME 45 9677
TEST_TIME 46 9593
TEST_TIME 47 9675
TEST_TIME 48 9619
TEST_TIME 49 9609
TEST_TIME 50 9519
TEST_TIME 51 9465
TEST_TIME 52 9402
TEST_TIME 53 9339
TEST_TIME 54 9242
TEST_TIME 55 9196
TEST_TIME 56 9134
TEST_TIME 57 9141
TEST_TIME 58 9253
TEST_TIME 59 9226
TEST_TIME 60 9236
TEST_TIME 61 9287
TEST_TIME 62 9252
TEST_TIME 63 9346
TEST_TIME 64 9318
TEST_TIME 65 9374
TEST_TIME 66 9465
TEST_TIME 67 9431
TEST_TIME 68 9502
TEST_TIME 69 9527
TEST_TIME 70 9624
TEST_TIME 71 9638
TEST_TIME 72 9642
TEST_TIME 73 9570
TEST_TIME 74 9452
TEST_TIME 75 9483
TEST_TIME 76 9487
TEST_TIME 77 9528
TEST_TIME 78 9476
TEST_TIME 79 9426
TEST_TIME 80 9358
TEST_TIME 81 9314
TEST_TIME 82 9359
TEST_TIME 83 9314
TEST_TIME 84 9352
TEST_TIME 85 9329
TEST_TIME 86 9462
TEST_TIME 87 9424
TEST_TIME 88 9459
TEST_TIME 89 9443
TEST_TIME 90 9471
TEST_TIME 91 9473
TEST_TIME 92 9514
TEST_TIME 93 9568
TEST_TIME 94 9527
TEST_TIME 95 9613
TEST_TIME 96 9639
TEST_TIME 97 9547
TEST_TIME 98 9495
TEST_TIME 99 9480
TEST_TIME 100 9510
TEST_TIME 101 9489
TEST_TIME 102 9454
TEST_TIME 103 9440
TEST_TIME 104 9413
TEST_TIME 105 9371
TEST_TIME 106 9430
TEST_TIME 107 9415
TEST_TIME 108 9400
TEST_TIME 109 9424
TEST_TIME 110 9462
TEST_TIME 111 9447
TEST_TIME 112 9470
TEST_TIME 113 9518
TEST_TIME 114 9481
TEST_TIME 115 9467
TEST_TIME 116 9484
TEST_TIME 117 9510
TEST_TIME 118 9566
TEST_TIME 119 9529
TEST_TIME 120 9455

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-07-2022 16:40:38 to 06-07-2022 16:40:41.

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 1578972 1578972 0 0.00 0 26493 6 0 0
doc_list_biud 1578971 1578971 0 0.00 0 69386 24 0 0
sp_add_invoice_to_stock 5290 5157 133 2.51 35 194480 17626 0 133
sp_cancel_adding_invoice 5768 5768 0 0.00 4 69436 744 0 0
sp_cancel_client_order 1887 1887 0 0.00 4 8777 329 0 0
sp_cancel_customer_reserve 563540 563540 0 0.00 0 24208 52 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 2 2135 55 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 1873 56 0 0
sp_cancel_supplier_invoice 6451 6438 13 0.20 1 69392 691 0 13
sp_cancel_supplier_order 398 398 0 0.00 5 990973 93283 0 0
sp_cancel_write_off 9825 9825 0 0.00 1 22752 112 0 0
sp_client_order 21388 21388 0 0.00 3 26297 316 0 0
sp_customer_reserve 481804 481804 0 0.00 0 82633 175 0 0
sp_fill_shopping_cart 32822 32822 0 0.00 1 14751 34 0 0
sp_fill_shopping_cart_clo_res 481797 481797 0 0.00 0 38551 13 0 0
sp_get_clo_for_invoice 5149 5149 0 0.00 0 35744 262 0 0
sp_kill_cost_storno 16642 16642 0 0.00 0 13246 50 0 0
sp_kill_qstorno_ret_qs2qd 1143741 1143741 0 0.00 0 69382 28 0 0
sp_kill_qty_storno 603350 603350 0 0.00 0 69385 61 0 0
sp_lock_selected_doc 21383 21383 0 0.00 0 7864 6 0 0
sp_make_cost_storno 28903 28903 0 0.00 0 21358 61 0 0
sp_multiply_rows_for_pdistr 28903 28903 0 0.00 0 23396 31 0 0
sp_multiply_rows_for_qdistr 21388 21388 0 0.00 0 15599 145 0 0
sp_pay_from_customer 7365 7365 0 0.00 4 26402 135 0 0
sp_pay_to_supplier 6044 6044 0 0.00 4 22657 141 0 0
sp_payment_common 13409 13409 0 0.00 1 23063 51 0 0
sp_qd_handle_on_cancel_clo 1887 1887 0 0.00 0 8772 299 0 0
sp_qd_handle_on_invoice_upd_sts 10917 10917 0 0.00 0 23735 291 0 0
sp_qd_handle_on_reserve_upd_sts 10345 10345 0 0.00 0 18172 52 0 0
sp_reserve_write_off 10345 10345 0 0.00 6 26599 194 0 0
sp_supplier_invoice 5742 5742 0 0.00 6 95558 2846 0 0
sp_supplier_order 5696 5696 0 0.00 5 41161 1126 0 0
srv_aggregate_perf_data 102 102 0 0.00 2105 318579 22369 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 3208 130611 39770 0 0
srv_find_qd_qs_mism 1083935 1083935 0 0.00 0 13667 5 0 0
srv_make_invnt_saldo 602 602 0 0.00 40 27709 863 0 0
srv_make_money_saldo 846 846 0 0.00 0 464 18 0 0
srv_recalc_idx_stat 4 4 0 0.00 127983 499876 258804 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5158 5158 0 0.00 0 15483 134 0 0
t$perf-norm:sp_cancel_adding_invoice 464 464 0 0.00 1 2223 74 0 0
t$perf-norm:sp_cancel_client_order 1887 1887 0 0.00 0 6635 23 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 1423 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 2977 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 696 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 627 8 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 28079 1481 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 3279 12 0 0
t$perf-norm:sp_client_order 21388 21388 0 0.00 0 10645 13 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 6742 11 0 0
t$perf-norm:sp_pay_from_customer 7365 7365 0 0.00 0 9950 12 0 0
t$perf-norm:sp_pay_to_supplier 6044 6044 0 0.00 0 6415 9 0 0
t$perf-norm:sp_reserve_write_off 10345 10345 0 0.00 0 9958 7 0 0
t$perf-norm:sp_supplier_invoice 5742 5742 0 0.00 0 6205 11 0 0
t$perf-norm:sp_supplier_order 5696 5696 0 0.00 0 17425 20 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 510 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 412 9 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 2576 20 0 0
t$perf-norm:srv_make_money_saldo 846 846 0 0.00 0 3633 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10564 10298 266 2.51 0 14148 18 0 266
v_all_customers 38916 38916 0 0.00 0 44 0 0 0
v_all_suppliers 31730 31730 0 0.00 0 150 1 0 0
v_all_wares 42776 42776 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 928 928 0 0.00 0 2567 8 0 0
v_cancel_client_order 3774 3774 0 0.00 0 1916 6 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 2118 6 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 2398 7 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 1335 6 0 0
v_cancel_supplier_invoice 858 832 26 3.03 0 1543 15 0 26
v_cancel_supplier_order 796 796 0 0.00 0 241 3 0 0
v_cancel_write_off 1960 1960 0 0.00 0 414 10 0 0
v_max_id_clo_ord 5696 5696 0 0.00 0 927 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 12601 32 0 0
v_max_id_ord_sup 5738 5738 0 0.00 0 14027 5 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 7371 8 0 0
v_max_non_paid_realizn 3933 3933 0 0.00 0 3262 6 0 0
v_min_id_clo_ord 5696 5696 0 0.00 0 3243 5 0 0
v_min_id_clo_res 993 993 0 0.00 0 4373 12 0 0
v_min_id_ord_sup 5738 5738 0 0.00 0 6711 7 0 0
v_min_non_paid_invoice 6044 6044 0 0.00 0 19578 29 0 0
v_min_non_paid_realizn 7365 7365 0 0.00 0 23058 55 0 0
v_our_firm 10724 10724 0 0.00 0 25 1 0 0
v_reserve_write_off 20690 20690 0 0.00 0 24174 12 0 0
x_lock_depdocs_on_canc_invoice 11790 11790 0 0.00 0 42265 99 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 25723 376 0 0
x_make_qstorno_1000_1200 5696 5696 0 0.00 1 41039 1060 0 0
x_make_qstorno_1200_2000 5742 5742 0 0.00 3 89569 2723 0 0
x_make_qstorno_2100_3300 481804 481804 0 0.00 0 82627 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-07-2022 16:40:42 to 06-07-2022 16:40:46.

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
14:08:28 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:29 11342 8192000000 9888391776 10201817088 977008288 10.655 100 82 164 0 1004311120 5846976 21455856
14:10:45 24203 8192000000 9941741584 10263879680 1025563504 11.126 100 81 162 0 1053900592 7509168 20827920
14:12:17 16969 8192000000 9986833776 10320789504 1070171264 11.554 100 84 168 0 1100023296 8992416 20859616
14:13:38 11332 8192000000 10019980832 10366328832 1109864272 11.931 100 82 165 1 1140958192 10670112 20423808
14:14:53 9366 8192000000 10086930848 10443317248 1167582720 12.474 100 82 161 1 1197741696 12000944 18158032
14:16:05 4538 8192000000 10128577664 10498203648 1212201072 12.889 100 75 150 0 1241970368 13067776 16701520
14:17:13 2456 8192000000 10161586832 10533412864 1243425168 13.178 100 85 170 0 1276205664 14164976 18615520
14:18:17 1498 8192000000 10182152384 10560430080 1264491296 13.371 100 87 173 1 1298777888 14403872 19882720
14:19:20 1630 8192000000 10196353888 10581512192 1284264752 13.552 100 80 158 0 1314278224 12066576 17946896
14:20:23 20224 8192000000 10200838304 10594930688 1293510848 13.636 100 65 129 1 1317311792 8845840 14955104
14:21:44 8209 8192000000 10197088320 10600939520 1299628208 13.692 100 22 44 1 1310453632 5584672 5240752
14:22:54 7433 8192000000 10192015200 10609160192 1308818704 13.775 100 21 42 0 1320418176 6678576 4920896
14:24:05 9001 8192000000 10203356992 10616393728 1313978224 13.822 100 11 22 0 1321608464 5121376 2508864
14:25:18 13217 8192000000 10213742128 10632085504 1327155280 13.941 100 29 61 1 1340480992 4827120 8498592
14:26:31 15166 8192000000 10226762032 10646913024 1331805600 13.983 100 48 97 1 1349440448 6505648 11129200
14:27:52 9366 8192000000 10200100480 10618118144 1301240880 13.707 99 22 44 0 1312998384 6279248 5478256
14:29:03 13185 8192000000 10197670304 10611216384 1301867664 13.712 99 17 34 1 1315535856 9830576 3837616
14:30:17 42767 8192000000 10174193024 10560241664 1252030304 13.257 98 44 84 1 1267046368 5025472 9990592
14:32:00 11356 8192000000 10111984528 10509443072 1179581216 12.586 95 24 46 1 1190712032 5896896 5233920
14:33:12 5887 8192000000 10154879472 10564718592 1268822976 13.411 100 26 52 1 1280644784 6159424 5662384
14:34:20 52027 8192000000 10184780288 10592292864 1289675040 13.601 100 32 66 0 1302438144 5060272 7702832
14:36:12 6704 8192000000 10202547808 10609778688 1301431008 13.708 100 35 71 1 1316713696 7430928 7851760
14:37:22 9518 8192000000 10197502752 10612752384 1306503312 13.754 100 21 41 0 1316333840 5235648 4594880
14:38:36 8703 8192000000 10234466928 10645471232 1332284880 13.988 100 29 56 0 1343650896 5697248 5668768
14:39:57 111474 8192000000 10259267776 10683203584 1354896832 14.192 100 41 82 0 1374746128 9538800 10310496
14:43:10 41878 8192000000 10272259904 10702852096 1364826848 14.281 100 31 63 0 1383440768 11668976 6944944
14:44:56 16792 8192000000 10292029520 10712018944 1373937296 14.362 100 54 108 0 1400166960 14060992 12168672
14:46:17 7480 8192000000 10306028976 10732785664 1378780128 14.406 99 53 105 0 1413408384 21294192 13334064
14:47:34 11501 8192000000 10342589280 10762887168 1402101456 14.614 100 58 114 0 1445404160 30267824 13034880
14:48:58 6226 8192000000 10337798128 10762903552 1404772064 14.637 100 53 103 1 1449035008 32510832 11752112
14:50:10 18722 8192000000 10342134848 10767290368 1402835056 14.620 100 50 100 1 1453930368 38698928 12396384
14:51:34 19834 8192000000 10350891616 10787586048 1414474048 14.724 100 39 76 0 1463296464 39160224 9662192
14:52:59 10932 8192000000 10343311952 10771513344 1405707168 14.646 100 45 90 0 1453652640 37102688 10842784
14:54:21 5003 8192000000 10365949232 10790285312 1414840688 14.727 100 60 119 0 1471916112 42891968 14183456
14:55:30 4247 8192000000 10349077264 10763370496 1390209376 14.508 100 66 130 1 1453151360 47329648 15612336
14:56:39 21265 8192000000 10340433408 10762444800 1381928320 14.434 100 37 73 0 1444298176 51980496 10389360
14:58:06 10995 8192000000 10333052560 10753843200 1358155808 14.221 98 33 66 0 1422449056 55116800 9176448
14:59:24 3207 8192000000 10376437568 10792689664 1391525152 14.519 100 65 130 0 1477929648 68886560 17517936
15:00:31 15152 8192000000 10339700480 10771988480 1372201536 14.347 99 45 90 0 1438400256 53645072 12553648
15:01:57 4793 8192000000 10315855088 10740006912 1369163568 14.320 100 55 110 0 1426708640 42642352 14902720
15:03:05 5014 8192000000 10302555088 10723938304 1333557152 13.999 98 33 66 0 1389701664 47068096 9076416
15:04:13 36191 8192000000 10285075520 10707202048 1324262480 13.915 98 34 68 0 1376232592 42445632 9524480
15:06:31 24377 8192000000 10252940624 10699157504 1314748512 13.829 98 15 29 1 1340101632 21307664 4045456
15:08:01 130611 8192000000 10285216576 10724524032 1350061728 14.148 99 13 24 0 1371031872 17832192 3137952
15:11:12 57513 8192000000 10250371792 10685583360 1306843104 13.757 96 36 71 1 1321161024 7555168 6762752
15:13:12 66773 8192000000 10286704304 10734149632 1368739744 14.316 100 12 24 0 1387326688 15663568 2923376
15:15:19 117388 8192000000 10319624496 10765824000 1379721936 14.414 99 13 26 1 1397315008 14723568 2869504
15:18:16 84120 8192000000 10300920064 10746433536 1376096176 14.382 99 32 64 0 1399914624 15791808 8026640
15:20:44 23913 8192000000 10304421776 10744586240 1380897520 14.425 99 23 46 0 1396918192 10966624 5054048
15:22:32 61890 8192000000 10296682080 10754301952 1384709856 14.459 99 15 28 1 1397655616 9967920 2977840
15:24:34 63044 8192000000 10294025984 10760093696 1395306208 14.553 100 18 36 0 1410305056 10754816 4244032
15:26:39 41099 8192000000 10303748544 10765328384 1403664624 14.628 100 11 22 0 1414637408 8339680 2633104
15:28:20 54915 8192000000 10300970992 10758098944 1379371152 14.411 98 15 28 1 1392401216 10183952 2846112
15:30:22 47758 8192000000 10293191264 10762665984 1397613712 14.574 100 12 24 0 1409876448 9252592 3010144
15:32:26 23724 8192000000 10311081712 10767253504 1407873520 14.665 100 30 57 1 1421392816 7387872 6131424
15:33:51 45234 8192000000 10304366720 10772647936 1398606752 14.583 99 11 22 0 1414860656 13632720 2621184
15:35:44 75040 8192000000 10293484688 10784010240 1385989264 14.470 99 6 12 1 1404414880 17135584 1290032
15:38:00 18796 8192000000 10295303872 10747682816 1375346672 14.375 99 43 86 0 1393374336 8620816 9406848
15:39:27 27241 8192000000 10292734880 10751557632 1375893104 14.380 99 24 48 0 1393326240 12387984 5045152
15:40:55 22804 8192000000 10299765968 10750918656 1384520800 14.457 100 32 65 0 1404222336 12570784 7130752
15:42:18 13869 8192000000 10272633984 10715295744 1344479920 14.098 98 30 60 1 1358830032 8119376 6230736
15:43:32 60238 8192000000 10250582896 10711355392 1293224896 13.634 94 9 18 0 1314954144 19514048 2215200
15:45:33 16045 8192000000 10278810128 10713395200 1352717664 14.172 99 51 100 0 1375548176 12072368 10758144
15:46:55 29722 8192000000 10256945520 10699333632 1302204080 13.715 96 19 38 0 1317162752 10858368 4100304
15:49:08 44953 8192000000 10241296384 10681503744 1291798384 13.621 96 29 58 0 1309864000 11468384 6597232
15:50:58 25487 8192000000 10272008096 10701287424 1346841584 14.119 99 44 87 1 1370100032 13808560 9449888
15:52:25 119829 8192000000 10246772400 10695655424 1316374416 13.844 97 36 72 1 1350365472 25637152 8353904
15:55:26 62596 8192000000 10238376384 10698752000 1336205408 14.023 99 13 26 0 1349530176 10729504 2595264
15:57:45 45433 8192000000 10249006176 10699935744 1321279136 13.888 97 13 26 0 1332629504 8681808 2668560
15:59:31 57046 8192000000 10242995728 10704171008 1326506336 13.936 98 4 8 0 1339833744 12420416 906992
16:01:33 51182 8192000000 10265865856 10724798464 1346326352 14.114 98 19 38 0 1363772032 12529568 4916112
16:03:32 60103 8192000000 10282666080 10737016832 1367832080 14.308 99 36 72 0 1389681200 13441872 8407248
16:05:41 32349 8192000000 10279150224 10725130240 1352640976 14.171 98 25 51 0 1368312080 10157280 5513824
16:07:13 28504 8192000000 10281905696 10724397056 1367479792 14.304 100 24 48 0 1383175088 10486896 5208400
16:08:45 15111 8192000000 10299656800 10742108160 1385560752 14.466 100 38 76 2 1403492352 9715392 8216208
16:10:02 7765 8192000000 10319192416 10758967296 1398925664 14.585 100 44 87 0 1417653248 9751936 8975648
16:11:16 42675 8192000000 10302668624 10766860288 1398758560 14.584 100 13 26 0 1413664320 11852144 3053616
16:13:04 7529 8192000000 10341227248 10778845184 1409490784 14.679 100 50 100 0 1435408096 15103120 10814192
16:14:44 69103 8192000000 10307784448 10774241280 1401855232 14.612 100 5 10 0 1423453200 20353120 1244848
16:17:00 36723 8192000000 10304923520 10765422592 1398572752 14.582 100 21 42 0 1417782048 14342944 4866352
16:18:40 54402 8192000000 10300940576 10765111296 1370812128 14.334 98 12 22 2 1393134864 19773808 2548928
16:20:35 29210 8192000000 10303821536 10764312576 1395172832 14.552 100 17 34 0 1420046160 20320736 4552592
16:22:07 53009 8192000000 10300335824 10754166784 1371131472 14.337 98 21 42 0 1388430752 12624736 4674544
16:26:00 22775 8192000000 10283958224 10723737600 1340375040 14.061 96 54 105 2 1360692032 7773344 12543648
16:27:24 59175 8192000000 10266292944 10717659136 1329145200 13.959 97 14 28 0 1340149488 8048912 2955376
16:29:23 49943 8192000000 10253397504 10707791872 1318586496 13.864 97 30 60 0 1341833120 16388400 6858224
16:32:04 56464 8192000000 10248977040 10683461632 1259553376 13.326 93 18 36 0 1278779168 15417088 3808704
16:34:00 66239 8192000000 10250430496 10689241088 1280661456 13.519 96 19 38 0 1301606256 16812960 4131840
16:36:07 15441 8192000000 10267516560 10693890048 1333427600 13.998 100 47 94 0 1359905136 15856944 10620592
16:37:23 85950 8192000000 9454534912 9770078208 592435936 6.744 44 18 36 0 599579696 3595504 3548256

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-07-2022 16:40:48 to 06-07-2022 16:41:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-07-2022 16:41:04 to 06-07-2022 16:41:06.

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          247420
MON$OLDEST_ACTIVE               247421
MON$OLDEST_SNAPSHOT             247421
MON$NEXT_TRANSACTION            247421
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                       2034976
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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'

06.07.22 16:41:07. 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-07-06 16:41:09 No severe PSQL-related problems occured
06.07.22 16:41:11. End of report.