Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.06.2022 01: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 16486968 kB
MemAvailable 31283180 kB
Buffers 0 kB
Cached 15202240 kB
SwapCached 2452 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-06-09 01:38:20
DTS_END                         2022-06-09 01:38:20.1790
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/50614
TRN_ID                          249126
ATT_ID                          699
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: 9486.40 20969 1138368
customer order: creation 184.42 324 22130
customer order: refuse 15.64 358 1877
order to supplier: creation 48.63 1239 5835
order to supplier: removal 3.56 91367 427
invoice (draft): creation 50.28 3220 6033
invoice (draft): removal 52.53 756 6303
invoice accept: apply 45.05 16727 5406
invoice accept: cancel 45.85 748 5502
customer reserve: creation 4178.98 166 501478
customer reserve: removal 4558.97 56 547076
realization accept: apply 89.14 165 10697
realization accept: cancel 73.31 102 8797
payment from customer: creation 64.17 87 7700
payment from customer: removal 6.64 78 797
payment to supplier: creation 51.94 132 6233
payment to supplier: removal 3.66 82 439
service: total inventory turnovers 5.43 807 652
service: total monetary turnovers 7.34 19 881
service: aggregate perf. data 0.84 23606 101
service: refresh index statistics 0.03 279341 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-06-2022 01:39:25 to 09-06-2022 01:39:27.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6432
TEST_TIME 2 7050
TEST_TIME 3 6666
TEST_TIME 4 5940
TEST_TIME 5 5186
TEST_TIME 6 5502
TEST_TIME 7 5474
TEST_TIME 8 5683
TEST_TIME 9 5818
TEST_TIME 10 6087
TEST_TIME 11 6385
TEST_TIME 12 6562
TEST_TIME 13 6481
TEST_TIME 14 6788
TEST_TIME 15 6733
TEST_TIME 16 6987
TEST_TIME 17 8344
TEST_TIME 18 8335
TEST_TIME 19 8441
TEST_TIME 20 9256
TEST_TIME 21 9156
TEST_TIME 22 9569
TEST_TIME 23 9967
TEST_TIME 24 10108
TEST_TIME 25 10732
TEST_TIME 26 10579
TEST_TIME 27 10719
TEST_TIME 28 10829
TEST_TIME 29 10858
TEST_TIME 30 10819
TEST_TIME 31 10759
TEST_TIME 32 10440
TEST_TIME 33 10450
TEST_TIME 34 10457
TEST_TIME 35 10341
TEST_TIME 36 10284
TEST_TIME 37 10201
TEST_TIME 38 10101
TEST_TIME 39 10252
TEST_TIME 40 10134
TEST_TIME 41 10106
TEST_TIME 42 10049
TEST_TIME 43 10066
TEST_TIME 44 9908
TEST_TIME 45 9945
TEST_TIME 46 10128
TEST_TIME 47 10069
TEST_TIME 48 10037
TEST_TIME 49 10085
TEST_TIME 50 10091
TEST_TIME 51 10051
TEST_TIME 52 10058
TEST_TIME 53 10151
TEST_TIME 54 10228
TEST_TIME 55 10163
TEST_TIME 56 10257
TEST_TIME 57 10220
TEST_TIME 58 10162
TEST_TIME 59 10002
TEST_TIME 60 9990
TEST_TIME 61 9958
TEST_TIME 62 9893
TEST_TIME 63 9871
TEST_TIME 64 9825
TEST_TIME 65 9774
TEST_TIME 66 9750
TEST_TIME 67 9745
TEST_TIME 68 9778
TEST_TIME 69 9801
TEST_TIME 70 9788
TEST_TIME 71 9817
TEST_TIME 72 9807
TEST_TIME 73 9776
TEST_TIME 74 9859
TEST_TIME 75 9851
TEST_TIME 76 9818
TEST_TIME 77 9806
TEST_TIME 78 9865
TEST_TIME 79 9832
TEST_TIME 80 9780
TEST_TIME 81 9794
TEST_TIME 82 9697
TEST_TIME 83 9690
TEST_TIME 84 9663
TEST_TIME 85 9633
TEST_TIME 86 9566
TEST_TIME 87 9584
TEST_TIME 88 9590
TEST_TIME 89 9585
TEST_TIME 90 9585
TEST_TIME 91 9605
TEST_TIME 92 9617
TEST_TIME 93 9636
TEST_TIME 94 9683
TEST_TIME 95 9661
TEST_TIME 96 9684
TEST_TIME 97 9663
TEST_TIME 98 9748
TEST_TIME 99 9770
TEST_TIME 100 9818
TEST_TIME 101 9810
TEST_TIME 102 9723
TEST_TIME 103 9712
TEST_TIME 104 9673
TEST_TIME 105 9650
TEST_TIME 106 9620
TEST_TIME 107 9656
TEST_TIME 108 9628
TEST_TIME 109 9602
TEST_TIME 110 9546
TEST_TIME 111 9496
TEST_TIME 112 9483
TEST_TIME 113 9465
TEST_TIME 114 9464
TEST_TIME 115 9455
TEST_TIME 116 9523
TEST_TIME 117 9560
TEST_TIME 118 9545
TEST_TIME 119 9522
TEST_TIME 120 9492

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-06-2022 01:39:28 to 09-06-2022 01:39:32.

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 1599193 1599193 0 0.00 0 28643 6 0 0
doc_list_biud 1599192 1599192 0 0.00 0 48464 25 0 0
sp_add_invoice_to_stock 5485 5406 79 1.44 47 158405 16727 0 79
sp_cancel_adding_invoice 5502 5502 0 0.00 4 68763 749 0 0
sp_cancel_client_order 1877 1877 0 0.00 4 10599 359 0 0
sp_cancel_customer_reserve 547076 547076 0 0.00 1 48467 56 0 0
sp_cancel_pay_from_customer 797 797 0 0.00 3 4002 79 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 2 4996 82 0 0
sp_cancel_supplier_invoice 6316 6303 13 0.20 2 28553 756 0 13
sp_cancel_supplier_order 427 427 0 0.00 6 1010189 91368 0 0
sp_cancel_write_off 8797 8797 0 0.00 1 8406 103 0 0
sp_client_order 22130 22130 0 0.00 3 17199 324 0 0
sp_customer_reserve 501478 501478 0 0.00 0 76039 166 0 0
sp_fill_shopping_cart 33993 33993 0 0.00 1 11924 35 0 0
sp_fill_shopping_cart_clo_res 501465 501465 0 0.00 0 13983 13 0 0
sp_get_clo_for_invoice 5392 5392 0 0.00 0 26113 255 0 0
sp_kill_cost_storno 15535 15535 0 0.00 0 17818 53 0 0
sp_kill_qstorno_ret_qs2qd 1109679 1109679 0 0.00 0 48461 31 0 0
sp_kill_qty_storno 586071 586071 0 0.00 0 48464 67 0 0
sp_lock_selected_doc 22342 22342 0 0.00 0 7802 5 0 0
sp_make_cost_storno 30022 30022 0 0.00 0 28619 48 0 0
sp_multiply_rows_for_pdistr 30022 30022 0 0.00 0 5836 23 0 0
sp_multiply_rows_for_qdistr 22130 22130 0 0.00 0 11691 148 0 0
sp_pay_from_customer 7700 7700 0 0.00 4 10511 87 0 0
sp_pay_to_supplier 6233 6233 0 0.00 5 28654 133 0 0
sp_payment_common 13933 13933 0 0.00 2 17345 21 0 0
sp_qd_handle_on_cancel_clo 1877 1877 0 0.00 0 10518 320 0 0
sp_qd_handle_on_invoice_upd_sts 10894 10894 0 0.00 0 24825 322 0 0
sp_qd_handle_on_reserve_upd_sts 10697 10697 0 0.00 0 20586 56 0 0
sp_reserve_write_off 10697 10697 0 0.00 6 20616 165 0 0
sp_supplier_invoice 6033 6033 0 0.00 6 102200 3220 0 0
sp_supplier_order 5835 5835 0 0.00 5 39471 1240 0 0
srv_aggregate_perf_data 101 101 0 0.00 2346 543625 23606 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2470 113652 44631 0 0
srv_find_qd_qs_mism 1085053 1085053 0 0.00 0 10947 6 0 0
srv_make_invnt_saldo 652 652 0 0.00 29 16689 807 0 0
srv_make_money_saldo 881 881 0 0.00 1 1353 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 162264 413661 279342 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 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 5406 5406 0 0.00 0 16414 163 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 10079 107 0 0
t$perf-norm:sp_cancel_client_order 1877 1877 0 0.00 0 5630 12 0 0
t$perf-norm:sp_cancel_customer_reserve 746 746 0 0.00 0 2445 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 797 797 0 0.00 0 491 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 788 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 475 475 0 0.00 0 2604 32 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 19386 1433 0 0
t$perf-norm:sp_cancel_write_off 1083 1083 0 0.00 0 3684 14 0 0
t$perf-norm:sp_client_order 22130 22130 0 0.00 0 6195 16 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 2012 6 0 0
t$perf-norm:sp_pay_from_customer 7700 7700 0 0.00 0 3352 7 0 0
t$perf-norm:sp_pay_to_supplier 6233 6233 0 0.00 0 3025 9 0 0
t$perf-norm:sp_reserve_write_off 10697 10697 0 0.00 0 7380 10 0 0
t$perf-norm:sp_supplier_invoice 6033 6033 0 0.00 0 4264 18 0 0
t$perf-norm:sp_supplier_order 5835 5835 0 0.00 0 6180 14 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 653 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 845 16 0 0
t$perf-norm:srv_make_invnt_saldo 652 652 0 0.00 0 4057 24 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 7617 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 14 7 0 0
v_add_invoice_to_stock 10940 10782 158 1.44 0 11758 20 0 158
v_all_customers 40072 40072 0 0.00 0 128 0 0 0
v_all_suppliers 32952 32952 0 0.00 0 27 1 0 0
v_all_wares 44258 44258 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1873 7 0 0
v_cancel_client_order 3754 3754 0 0.00 0 10526 9 0 0
v_cancel_customer_prepayment 1594 1594 0 0.00 0 3985 9 0 0
v_cancel_customer_reserve 1492 1492 0 0.00 0 11340 16 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 4943 16 0 0
v_cancel_supplier_invoice 976 950 26 2.66 0 2389 17 0 26
v_cancel_supplier_order 854 854 0 0.00 0 3538 18 0 0
v_cancel_write_off 2166 2166 0 0.00 0 3079 12 0 0
v_max_id_clo_ord 5835 5835 0 0.00 0 1150 1 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 3459 11 0 0
v_max_id_ord_sup 6029 6029 0 0.00 0 2124 2 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 1181 3 0 0
v_max_non_paid_realizn 4319 4319 0 0.00 0 1984 4 0 0
v_min_id_clo_ord 5835 5835 0 0.00 0 6257 7 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 3896 13 0 0
v_min_id_ord_sup 6029 6029 0 0.00 0 6359 10 0 0
v_min_non_paid_invoice 6233 6233 0 0.00 0 17234 21 0 0
v_min_non_paid_realizn 7700 7700 0 0.00 0 3324 12 0 0
v_our_firm 10948 10948 0 0.00 0 13 1 0 0
v_reserve_write_off 21394 21394 0 0.00 0 11770 10 0 0
x_lock_depdocs_on_canc_invoice 11330 11330 0 0.00 0 22357 100 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 20622 307 0 0
x_make_qstorno_1000_1200 5835 5835 0 0.00 2 39443 1164 0 0
x_make_qstorno_1200_2000 6033 6033 0 0.00 3 102009 3097 0 0
x_make_qstorno_2100_3300 501478 501478 0 0.00 0 76037 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-06-2022 01:39:33 to 09-06-2022 01:39:37.

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
23:08:23 1 8192000000 8798662400 8809840640 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:24 34534 8192000000 9891195728 10204860416 975264784 10.638 100 82 163 1 1002772320 6014912 21492624
23:11:03 25636 8192000000 9945654912 10269999104 1028652944 11.155 100 89 178 1 1060363024 8561792 23148288
23:12:37 10018 8192000000 9988975088 10320011264 1072804000 11.579 100 87 174 0 1104304048 9829600 21670448
23:13:49 12122 8192000000 10031110096 10376392704 1114475216 11.975 100 77 154 0 1142822400 10290000 18057184
23:15:05 6825 8192000000 10089018144 10448224256 1172160368 12.517 100 77 153 0 1202132528 12598432 17373728
23:16:13 6882 8192000000 10129622144 10498015232 1213886480 12.905 100 71 142 0 1241387216 11330592 16170144
23:17:23 3349 8192000000 10165122976 10543890432 1258612160 13.317 100 84 167 2 1287915488 10856960 18446368
23:18:28 2083 8192000000 10207174736 10587701248 1285189232 13.560 100 90 180 1 1320079520 14606816 20283472
23:19:32 2547 8192000000 10223617472 10609332224 1307849376 13.767 100 94 187 0 1342420544 13223360 21347808
23:20:36 6532 8192000000 10220914656 10616025088 1311685744 13.801 100 64 130 1 1332074032 5894192 14494096
23:21:44 4222 8192000000 10221456576 10629410816 1323604224 13.909 100 47 94 0 1343958496 9760592 10593680
23:22:49 6167 8192000000 10214029488 10629918720 1327260560 13.942 100 30 57 3 1339882112 5268800 7352752
23:23:56 9529 8192000000 10217116832 10638585856 1334971808 14.012 100 23 46 0 1346552080 5720608 5859664
23:25:11 45299 8192000000 10251596000 10655940608 1341780448 14.073 100 57 114 2 1363008240 5849728 15378064
23:26:56 4936 8192000000 10233811888 10655367168 1332101344 13.986 99 38 74 0 1348974320 7146912 9726064
23:28:04 35531 8192000000 10215245936 10639609856 1296602240 13.664 97 19 38 0 1305660704 4906912 4151552
23:29:41 5812 8192000000 10174461840 10576732160 1272032192 13.440 98 25 50 0 1285522688 7366288 6124208
23:30:54 9718 8192000000 10168206016 10572337152 1265081984 13.377 99 20 40 0 1275746384 6088192 4576208
23:32:08 48717 8192000000 10140696544 10546020352 1223058560 12.990 97 19 37 0 1232638080 5303008 4276512
23:33:57 6372 8192000000 10134724992 10532528128 1240039840 13.147 98 20 40 1 1250017264 5162224 4815200
23:35:06 4907 8192000000 10183557728 10587840512 1285482272 13.563 100 30 60 0 1298415088 5027968 7904848
23:36:15 41383 8192000000 10190516608 10608898048 1292166368 13.624 99 30 61 1 1306554432 5381248 9006816
23:38:09 7361 8192000000 10214379840 10630397952 1320064112 13.877 100 25 50 3 1330949696 4696848 6188736
23:39:21 17188 8192000000 10261161808 10670415872 1346146960 14.113 100 57 115 2 1369554672 10444880 12962832
23:40:43 12492 8192000000 10267925168 10687504384 1362644096 14.261 100 47 94 0 1383106416 10177664 10284656
23:42:00 20634 8192000000 10297481232 10717487104 1381847920 14.433 100 37 74 0 1407014800 16636752 8530128
23:43:24 39707 8192000000 10309601744 10737418240 1394505376 14.546 100 41 82 1 1428050576 23102032 10443168
23:45:08 5237 8192000000 10320171648 10742480896 1397128416 14.569 100 55 110 0 1433654752 23536016 12990320
23:46:18 16293 8192000000 10331410176 10756030464 1405470864 14.644 100 52 104 1 1441713488 24024864 12217760
23:47:48 8024 8192000000 10353587392 10776313856 1415094624 14.729 100 59 118 1 1460810288 31074576 14641088
23:48:59 3253 8192000000 10375374528 10794737664 1426985872 14.835 100 80 160 1 1481677248 35152912 19538464
23:50:06 3507 8192000000 10365544176 10789597184 1426070736 14.826 100 70 140 1 1475292544 31884240 17337568
23:51:16 4686 8192000000 10372969888 10787565568 1422736080 14.797 100 69 136 0 1476593744 37278736 16578928
23:52:30 6459 8192000000 10359825776 10778284032 1403465168 14.626 99 69 138 1 1463331168 41597664 18268336
23:53:45 3730 8192000000 10369683888 10781106176 1407037728 14.658 100 74 147 1 1470958656 45326064 18594864
23:54:57 4555 8192000000 10391960800 10804236288 1423054256 14.800 100 85 170 1 1495788640 50578656 22155728
23:56:04 12111 8192000000 10349932288 10773987328 1405477824 14.644 100 37 73 0 1449075936 33201216 10396896
23:57:35 2469 8192000000 10363065168 10778681344 1399823152 14.593 99 77 152 2 1463197168 43412480 19961536
23:58:39 35250 8192000000 10329122896 10762285056 1386541584 14.475 99 30 60 2 1433570848 38312528 8716736
00:00:15 16106 8192000000 10302556144 10745208832 1364541856 14.278 99 26 52 1 1407408432 35535840 7330736
00:01:34 20256 8192000000 10295605680 10739863552 1342031888 14.076 99 21 41 1 1390380016 42197712 6150416
00:03:03 32845 8192000000 10245111040 10688409600 1294432304 13.645 96 11 22 0 1326062512 28667888 2962320
00:04:37 80720 8192000000 10250099104 10686177280 1305650480 13.747 97 24 48 1 1325684784 14179344 5854960
00:07:07 113651 8192000000 10252964768 10690015232 1279151440 13.505 95 22 44 2 1301646416 17324640 5170336
00:10:01 98943 8192000000 10251976256 10681479168 1324592656 13.918 99 28 55 1 1341429584 8855712 7981216
00:12:52 51495 8192000000 10282453520 10711842816 1354364560 14.187 100 27 55 0 1376676224 15675648 6636016
00:14:55 28693 8192000000 10301613856 10732449792 1367412592 14.304 100 37 74 3 1392356160 15568000 9375568
00:16:32 53789 8192000000 10286763376 10738954240 1367793152 14.307 100 22 45 0 1400742528 27511104 5438272
00:19:08 102621 8192000000 10292926016 10754588672 1350454048 14.152 98 21 42 0 1379056224 23625216 4976960
00:21:52 60014 8192000000 10292564320 10754519040 1381372512 14.429 100 16 32 1 1400964352 15805376 3786464
00:23:53 60957 8192000000 10280919408 10744909824 1383087088 14.444 100 9 17 1 1393394608 8346320 1961200
00:25:57 30682 8192000000 10311177328 10753642496 1388831536 14.495 100 52 104 2 1410185536 9135584 12218416
00:27:31 60259 8192000000 10301973952 10762813440 1399555872 14.591 100 9 18 0 1412103504 10590784 1956848
00:29:31 20628 8192000000 10303698752 10750251008 1369018464 14.318 98 43 86 2 1392280288 13262256 9999568
00:30:56 104884 8192000000 10302149792 10771976192 1385691536 14.467 99 7 14 1 1405835520 18105232 2038752
00:33:42 11991 8192000000 10322151072 10763894784 1398238928 14.579 100 40 81 0 1417332144 9362256 9730960
00:34:59 58101 8192000000 10274227088 10725490688 1286373152 13.571 92 11 22 0 1298062288 9225312 2463824
00:36:57 84211 8192000000 10293620656 10749186048 1385929872 14.470 100 31 61 1 1400786224 6346320 8510032
00:39:37 47664 8192000000 10300722816 10749497344 1392399216 14.527 100 15 30 0 1405199728 9579488 3221024
00:41:25 49738 8192000000 10274072400 10735980544 1348428784 14.133 97 30 59 3 1367226944 11289184 7508976
00:43:20 55300 8192000000 10287164560 10740850688 1382985056 14.443 100 14 28 0 1396601472 10254752 3361664
00:45:16 57358 8192000000 10295373296 10756489216 1346180240 14.113 97 14 28 1 1367888416 17944240 3763936
00:47:50 43579 8192000000 10262402096 10712150016 1338278192 14.042 97 23 45 1 1354497312 10894672 5324448
00:49:34 53475 8192000000 10291010816 10743091200 1372978512 14.354 100 23 46 0 1387541120 9475344 5087264
00:51:28 19305 8192000000 10285084752 10721808384 1364084144 14.274 100 49 98 1 1386313904 11203296 11026464
00:52:51 48463 8192000000 10252859632 10701516800 1308973872 13.777 96 12 24 0 1319788496 8215216 2599408
00:54:40 13862 8192000000 10286466656 10720931840 1361410368 14.250 100 44 88 1 1382908736 12047312 9451056
00:55:56 25654 8192000000 10269884192 10716401664 1347532896 14.125 99 30 60 1 1369067616 15009616 6525104
00:57:27 25343 8192000000 10269043856 10700197888 1327705600 13.946 98 37 74 0 1346215552 10645200 7864752
00:58:52 89586 8192000000 10252154256 10694819840 1326547392 13.936 98 24 49 0 1340945920 9521392 4877136
01:02:14 66806 8192000000 10242515456 10695622656 1339140192 14.050 99 12 24 0 1351768352 9834896 2793264
01:04:36 72925 8192000000 10262269440 10724032512 1350918304 14.156 99 14 28 1 1366281104 11945648 3417152
01:06:49 78833 8192000000 10266904640 10719834112 1337664416 14.036 97 18 36 0 1353961264 12410448 3886400
01:09:11 62500 8192000000 10290230656 10735443968 1373115488 14.355 99 21 42 0 1393016480 15461936 4439056
01:11:13 59322 8192000000 10285485008 10747113472 1382046592 14.435 100 9 18 0 1396823200 12620336 2156272
01:13:13 63658 8192000000 10300801824 10744131584 1356328480 14.204 98 26 52 1 1371545248 9783968 5432800
01:15:25 50039 8192000000 10279063248 10722684928 1336317376 14.024 97 22 44 0 1361163376 19592688 5253312
01:17:16 59464 8192000000 10296286112 10748657664 1377641872 14.395 100 28 56 1 1395391296 11546768 6202656
01:19:17 62608 8192000000 10292916496 10748100608 1364538672 14.278 98 7 14 0 1388216768 22288320 1389776
01:21:19 68348 8192000000 10276102928 10724790272 1350620800 14.153 97 16 32 0 1361702592 7897360 3184432
01:24:26 82381 8192000000 10284095616 10758369280 1363789312 14.271 98 10 19 1 1380008016 14026816 2191888
01:26:48 68833 8192000000 10268907792 10724851712 1338737792 14.046 96 11 22 0 1347236912 6291856 2207264
01:30:07 46463 8192000000 10269174672 10730356736 1367408464 14.304 99 17 35 0 1378352032 7229056 3714512
01:32:41 60205 8192000000 10318814096 10763190272 1363876224 14.272 97 19 38 0 1382638736 13862800 4899712
01:34:52 69708 8192000000 10280841088 10738352128 1347460400 14.125 98 12 24 0 1359351760 9392560 2498800
01:37:09 23848 8192000000 10282113264 10711171072 1314458944 13.827 96 45 89 1 1333416480 9025376 9932160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 09-06-2022 01:39:39 to 09-06-2022 01:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 79 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 09-06-2022 01:39:54 to 09-06-2022 01:39:56.

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          245162
MON$OLDEST_ACTIVE               249238
MON$OLDEST_SNAPSHOT             249238
MON$NEXT_TRANSACTION            249238
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                       2008448
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:217: 
:218: mscs-test-161.budzdorov.ru    Thu Jun  9 01:38:23 2022
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:220: 
:221: 
:222: mscs-test-161.budzdorov.ru    Thu Jun  9 01:38:31 2022
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:224: 
:225: 
:226: mscs-test-161.budzdorov.ru    Thu Jun  9 01:38:31 2022
:227:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:228: 
:229: 
:230: mscs-test-161.budzdorov.ru    Thu Jun  9 01:38:31 2022
:231:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:232: 
--- end of diff output ---

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

09.06.22 01:39:57. 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-06-09 01:40:00 No severe PSQL-related problems occured
09.06.22 01:40:01. End of report.