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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14746804 kB
MemAvailable 30113312 kB
Buffers 0 kB
Cached 16938792 kB
SwapCached 1716 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-12-06 19:38:31
DTS_END                         2022-12-06 19:38:31.9500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43724
TRN_ID                          243451
ATT_ID                          631
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: 9767.04 19443 1172045
customer order: creation 174.30 353 20916
customer order: refuse 14.96 368 1795
order to supplier: creation 46.10 1248 5532
order to supplier: removal 3.69 92666 443
invoice (draft): creation 46.70 3455 5604
invoice (draft): removal 58.47 722 7016
invoice accept: apply 42.62 17534 5114
invoice accept: cancel 52.83 737 6340
customer reserve: creation 3859.14 176 463097
customer reserve: removal 5154.09 51 618491
realization accept: apply 85.28 189 10234
realization accept: cancel 96.16 91 11539
payment from customer: creation 61.33 111 7360
payment from customer: removal 6.02 90 722
payment to supplier: creation 49.97 145 5996
payment to supplier: removal 3.33 73 400
service: total inventory turnovers 4.42 1056 530
service: total monetary turnovers 6.73 36 807
service: aggregate perf. data 0.88 21636 105
service: refresh index statistics 0.03 248132 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-12-2022 19:39:19 to 06-12-2022 19:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7071
TEST_TIME 2 4635
TEST_TIME 3 4126
TEST_TIME 4 4382
TEST_TIME 5 4783
TEST_TIME 6 5174
TEST_TIME 7 5409
TEST_TIME 8 5433
TEST_TIME 9 5724
TEST_TIME 10 5583
TEST_TIME 11 5581
TEST_TIME 12 5739
TEST_TIME 13 6075
TEST_TIME 14 6455
TEST_TIME 15 6942
TEST_TIME 16 6907
TEST_TIME 17 7095
TEST_TIME 18 7229
TEST_TIME 19 7482
TEST_TIME 20 7747
TEST_TIME 21 8029
TEST_TIME 22 8241
TEST_TIME 23 8208
TEST_TIME 24 8728
TEST_TIME 25 8719
TEST_TIME 26 9070
TEST_TIME 27 9675
TEST_TIME 28 9567
TEST_TIME 29 9710
TEST_TIME 30 10057
TEST_TIME 31 10200
TEST_TIME 32 10648
TEST_TIME 33 10406
TEST_TIME 34 10230
TEST_TIME 35 10344
TEST_TIME 36 10477
TEST_TIME 37 10399
TEST_TIME 38 10229
TEST_TIME 39 10182
TEST_TIME 40 10214
TEST_TIME 41 10113
TEST_TIME 42 10158
TEST_TIME 43 10074
TEST_TIME 44 10029
TEST_TIME 45 9991
TEST_TIME 46 9978
TEST_TIME 47 9885
TEST_TIME 48 9875
TEST_TIME 49 9926
TEST_TIME 50 9862
TEST_TIME 51 9861
TEST_TIME 52 9862
TEST_TIME 53 9768
TEST_TIME 54 9691
TEST_TIME 55 9649
TEST_TIME 56 9574
TEST_TIME 57 9597
TEST_TIME 58 9468
TEST_TIME 59 9484
TEST_TIME 60 9468
TEST_TIME 61 9550
TEST_TIME 62 9537
TEST_TIME 63 9619
TEST_TIME 64 9657
TEST_TIME 65 9707
TEST_TIME 66 9742
TEST_TIME 67 9704
TEST_TIME 68 9756
TEST_TIME 69 9786
TEST_TIME 70 9887
TEST_TIME 71 9892
TEST_TIME 72 9901
TEST_TIME 73 9822
TEST_TIME 74 9784
TEST_TIME 75 9709
TEST_TIME 76 9649
TEST_TIME 77 9644
TEST_TIME 78 9616
TEST_TIME 79 9597
TEST_TIME 80 9573
TEST_TIME 81 9493
TEST_TIME 82 9449
TEST_TIME 83 9513
TEST_TIME 84 9467
TEST_TIME 85 9551
TEST_TIME 86 9619
TEST_TIME 87 9617
TEST_TIME 88 9673
TEST_TIME 89 9639
TEST_TIME 90 9635
TEST_TIME 91 9599
TEST_TIME 92 9705
TEST_TIME 93 9739
TEST_TIME 94 9751
TEST_TIME 95 9821
TEST_TIME 96 9818
TEST_TIME 97 9773
TEST_TIME 98 9734
TEST_TIME 99 9703
TEST_TIME 100 9648
TEST_TIME 101 9693
TEST_TIME 102 9711
TEST_TIME 103 9689
TEST_TIME 104 9650
TEST_TIME 105 9659
TEST_TIME 106 9611
TEST_TIME 107 9535
TEST_TIME 108 9554
TEST_TIME 109 9571
TEST_TIME 110 9557
TEST_TIME 111 9530
TEST_TIME 112 9526
TEST_TIME 113 9546
TEST_TIME 114 9539
TEST_TIME 115 9519
TEST_TIME 116 9567
TEST_TIME 117 9634
TEST_TIME 118 9772
TEST_TIME 119 9776
TEST_TIME 120 9772

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-12-2022 19:39:22 to 06-12-2022 19:39:26.

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 1601747 1601747 0 0.00 0 27577 6 0 0
doc_list_biud 1601747 1601747 0 0.00 0 76642 26 0 0
sp_add_invoice_to_stock 5213 5114 99 1.89 34 169418 17534 0 99
sp_cancel_adding_invoice 6340 6340 0 0.00 5 71865 737 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 28068 368 0 0
sp_cancel_customer_reserve 618491 618491 0 0.00 0 76648 52 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 2 3855 91 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 3 2621 74 0 0
sp_cancel_supplier_invoice 7031 7016 15 0.21 2 17224 722 0 15
sp_cancel_supplier_order 443 443 0 0.00 4 981297 92666 0 0
sp_cancel_write_off 11539 11539 0 0.00 1 7013 91 0 0
sp_client_order 20916 20916 0 0.00 3 32237 354 0 0
sp_customer_reserve 463097 463097 0 0.00 0 61516 176 0 0
sp_fill_shopping_cart 32047 32047 0 0.00 1 16918 41 0 0
sp_fill_shopping_cart_clo_res 463092 463092 0 0.00 0 28183 15 0 0
sp_get_clo_for_invoice 5106 5106 0 0.00 0 32690 342 0 0
sp_kill_cost_storno 19001 19001 0 0.00 0 7260 51 0 0
sp_kill_qstorno_ret_qs2qd 1255980 1255980 0 0.00 0 76625 28 0 0
sp_kill_qty_storno 660964 660964 0 0.00 0 76641 61 0 0
sp_lock_selected_doc 21202 21202 0 0.00 0 10380 7 0 0
sp_make_cost_storno 28696 28696 0 0.00 0 27570 67 0 0
sp_multiply_rows_for_pdistr 28696 28696 0 0.00 0 8279 32 0 0
sp_multiply_rows_for_qdistr 20916 20916 0 0.00 0 10152 159 0 0
sp_pay_from_customer 7360 7360 0 0.00 4 21043 111 0 0
sp_pay_to_supplier 5996 5996 0 0.00 4 21372 145 0 0
sp_payment_common 13356 13356 0 0.00 1 11070 26 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 28059 333 0 0
sp_qd_handle_on_invoice_upd_sts 11446 11446 0 0.00 0 15405 313 0 0
sp_qd_handle_on_reserve_upd_sts 10234 10234 0 0.00 0 18751 60 0 0
sp_reserve_write_off 10234 10234 0 0.00 6 27608 189 0 0
sp_supplier_invoice 5604 5604 0 0.00 5 107580 3455 0 0
sp_supplier_order 5532 5532 0 0.00 4 97822 1248 0 0
srv_aggregate_perf_data 105 105 0 0.00 3201 201691 21637 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1184 136906 42682 0 0
srv_find_qd_qs_mism 1124121 1124121 0 0.00 0 7403 4 0 0
srv_make_invnt_saldo 530 530 0 0.00 24 30145 1057 0 0
srv_make_money_saldo 807 807 0 0.00 1 5883 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 178463 347176 248132 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5114 5114 0 0.00 0 17079 204 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 2082 75 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 2417 14 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 1195 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 705 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 4660 33 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 1300 15 0 0
t$perf-norm:sp_cancel_supplier_order 443 443 0 0.00 0 24646 1667 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 4958 20 0 0
t$perf-norm:sp_client_order 20916 20916 0 0.00 0 5434 17 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 2593 12 0 0
t$perf-norm:sp_pay_from_customer 7360 7360 0 0.00 0 13193 17 0 0
t$perf-norm:sp_pay_to_supplier 5996 5996 0 0.00 0 8462 12 0 0
t$perf-norm:sp_reserve_write_off 10234 10234 0 0.00 0 9310 14 0 0
t$perf-norm:sp_supplier_invoice 5604 5604 0 0.00 0 16172 25 0 0
t$perf-norm:sp_supplier_order 5532 5532 0 0.00 0 5130 16 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 1238 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 273 15 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 14754 53 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 1625 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10410 10212 198 1.90 0 4995 16 0 198
v_all_customers 38134 38134 0 0.00 0 46 1 0 0
v_all_suppliers 30730 30730 0 0.00 0 27 1 0 0
v_all_wares 41832 41832 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 3170 13 0 0
v_cancel_client_order 3590 3590 0 0.00 0 2805 7 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 3755 10 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 2145 6 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 2556 6 0 0
v_cancel_supplier_invoice 870 840 30 3.44 0 1813 15 0 30
v_cancel_supplier_order 886 886 0 0.00 0 2217 11 0 0
v_cancel_write_off 1992 1992 0 0.00 0 2242 16 0 0
v_max_id_clo_ord 5530 5530 0 0.00 0 547 1 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 1471 12 0 0
v_max_id_ord_sup 5601 5601 0 0.00 0 7789 6 0 0
v_max_non_paid_invoice 1762 1762 0 0.00 0 441 3 0 0
v_max_non_paid_realizn 3934 3934 0 0.00 0 4433 5 0 0
v_min_id_clo_ord 5530 5530 0 0.00 0 7393 9 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 28061 37 0 0
v_min_id_ord_sup 5601 5601 0 0.00 0 10207 22 0 0
v_min_non_paid_invoice 5996 5996 0 0.00 0 10864 15 0 0
v_min_non_paid_realizn 7360 7360 0 0.00 0 6755 21 0 0
v_our_firm 10550 10550 0 0.00 0 16 1 0 0
v_reserve_write_off 20468 20468 0 0.00 0 4140 5 0 0
x_lock_depdocs_on_canc_invoice 12936 12936 0 0.00 0 12591 101 0 0
x_lock_depdocs_on_canc_sup_ord 443 443 0 0.00 0 8750 373 0 0
x_make_qstorno_1000_1200 5532 5532 0 0.00 1 97815 1166 0 0
x_make_qstorno_1200_2000 5604 5604 0 0.00 2 107556 3306 0 0
x_make_qstorno_2100_3300 463096 463096 0 0.00 0 61515 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-12-2022 19:39:27 to 06-12-2022 19:39:31.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:35 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:36 27025 8192000000 9904352944 10217304064 988936640 10.771 100 86 172 0 1017938592 6276576 22725376
17:11:06 18429 8192000000 9957838512 10283040768 1043092528 11.294 100 90 180 0 1074340176 8294848 22952800
17:12:35 11241 8192000000 9988280800 10319331328 1070707152 11.559 100 90 180 0 1103630464 10606384 22316928
17:13:50 14594 8192000000 10036300768 10382020608 1126835472 12.092 100 81 161 0 1157192912 10976336 19381104
17:15:11 5466 8192000000 10091057280 10450714624 1180301408 12.593 100 79 159 0 1210547296 11859664 18386224
17:16:20 4456 8192000000 10126651424 10498011136 1216541872 12.930 100 82 165 1 1247967664 13236768 18189024
17:17:27 1425 8192000000 10171766112 10545348608 1251808208 13.255 100 87 172 0 1288259232 17910672 18540352
17:18:30 4126 8192000000 10175371632 10557440000 1268185216 13.405 100 80 157 2 1298130000 13132112 16812672
17:19:35 865 8192000000 10210571008 10593734656 1293790368 13.639 100 91 181 0 1328644912 14308592 20545952
17:20:37 6382 8192000000 10194431488 10597335040 1299154016 13.688 100 49 95 1 1317249088 6528080 11566992
17:21:45 10572 8192000000 10187506352 10606469120 1306177504 13.751 100 23 47 0 1318124688 6726672 5220512
17:22:57 17476 8192000000 10199204608 10620166144 1318524032 13.863 100 13 26 0 1326425264 5243840 2657392
17:24:15 3877 8192000000 10215712832 10630758400 1327539344 13.945 100 26 52 0 1339195888 6070544 5586000
17:25:20 44401 8192000000 10239030464 10646470656 1334138432 14.005 100 56 110 0 1354053760 7566224 12349104
17:27:05 6763 8192000000 10232373712 10651652096 1336957536 14.030 100 30 60 1 1350925888 7060384 6907968
17:28:14 12110 8192000000 10218022528 10630541312 1289083376 13.596 97 28 57 1 1300344160 4883840 6376944
17:29:28 69003 8192000000 10175931472 10587688960 1206460224 12.836 92 29 58 0 1218228944 5290352 6478368
17:31:38 6444 8192000000 10150744432 10553860096 1240910608 13.155 98 35 71 0 1255264592 6170016 8183968
17:32:45 4668 8192000000 10142688928 10544455680 1251596704 13.253 100 25 49 1 1262407248 5235536 5575008
17:33:51 56703 8192000000 10161367488 10557968384 1229919584 13.053 97 27 54 0 1242229136 5578688 6730864
17:35:55 7555 8192000000 10186368320 10590117888 1287291792 13.580 100 29 58 1 1299178176 5635520 6250864
17:37:06 5683 8192000000 10208870944 10617765888 1304191920 13.733 100 25 50 3 1318978624 9905600 4881104
17:38:13 8195 8192000000 10214826224 10632376320 1324880144 13.921 100 18 35 0 1333918544 5391776 3646624
17:39:30 26785 8192000000 10252283184 10669019136 1349820816 14.146 100 52 103 2 1369809808 8383200 11605792
17:40:59 43692 8192000000 10265777168 10685304832 1364454768 14.277 100 37 76 1 1382075440 8812512 8808160
17:42:49 84849 8192000000 10280121184 10717208576 1381082720 14.426 100 37 74 0 1405319760 15130560 9106480
17:45:32 17149 8192000000 10302117408 10728660992 1391659616 14.521 100 38 76 0 1415430320 14862384 8908320
17:46:50 11195 8192000000 10316374592 10741690368 1393792608 14.540 100 49 98 2 1427041728 21717632 11531488
17:48:11 12992 8192000000 10319688832 10748424192 1401061152 14.604 100 43 86 0 1433076288 21281136 10734000
17:49:33 2332 8192000000 10323308608 10750062592 1402566784 14.618 100 61 119 0 1439838352 22625200 14646368
17:50:41 4078 8192000000 10319157936 10740072448 1382883280 14.442 99 66 130 1 1424160976 25427328 15850368
17:51:48 1182 8192000000 10329322608 10750525440 1401034624 14.604 100 71 137 1 1444860208 26715072 17110512
17:52:54 1856 8192000000 10346698240 10759876608 1399980000 14.595 100 76 149 2 1449523152 31823456 17719696
17:53:58 8030 8192000000 10325646944 10745810944 1391126864 14.516 100 65 125 0 1435153248 28516688 15509696
17:55:15 2126 8192000000 10312691840 10711244800 1357775088 14.217 100 86 169 0 1410795520 31833024 21187408
17:56:22 3719 8192000000 10305667776 10716753920 1366479152 14.295 100 68 130 1 1415500608 32422160 16599296
17:57:34 4892 8192000000 10301499952 10712268800 1355471968 14.197 100 73 139 2 1410004992 36625008 17908016
17:58:42 2733 8192000000 10325575440 10732675072 1373181504 14.356 100 79 157 0 1427879280 34647312 20050464
17:59:51 16705 8192000000 10324431584 10748690432 1376545920 14.386 99 43 87 0 1421024896 32393296 12085680
18:01:15 1556 8192000000 10349068912 10756128768 1380596896 14.422 99 88 175 0 1441588800 39102336 21889568
18:02:21 7764 8192000000 10314147936 10739372032 1364445888 14.277 99 42 84 0 1417325088 41150576 11728624
18:03:33 10569 8192000000 10324525120 10746171392 1376781920 14.388 100 39 75 0 1425569408 38317088 10470400
18:04:47 34557 8192000000 10315882512 10748526592 1362050896 14.256 99 33 66 0 1415403376 43835088 9517392
18:06:27 25219 8192000000 10304676704 10738610176 1361690816 14.253 99 20 40 0 1397820304 30975120 5154368
18:08:02 19983 8192000000 10310709472 10750369792 1371300144 14.339 100 26 53 0 1413527120 34983760 7243216
18:09:26 46909 8192000000 10300608160 10765316096 1371764720 14.343 99 8 16 0 1407372816 33891728 1716368
18:11:17 19540 8192000000 10327703632 10761715712 1402421312 14.617 100 42 82 1 1423206672 11658896 9126464
18:12:41 40270 8192000000 10315173536 10767429632 1402374096 14.616 100 18 36 0 1420695440 13472368 4848976
18:14:28 56271 8192000000 10315102416 10757509120 1374604144 14.368 98 22 44 0 1394155792 15276128 4275520
18:16:41 136305 8192000000 10320429392 10781892608 1398715632 14.584 100 36 73 0 1420295440 12967120 8612688
18:20:02 23319 8192000000 10334160864 10765336576 1387823808 14.486 99 58 117 2 1411334192 9596752 13913632
18:21:26 49603 8192000000 10301920336 10759544832 1387556432 14.484 99 21 42 0 1400123072 7789984 4776656
18:23:17 35397 8192000000 10312959104 10769686528 1404456560 14.635 100 23 46 0 1418093952 8504208 5133184
18:24:55 26249 8192000000 10307986848 10760486912 1388919152 14.496 99 39 77 1 1409492112 10453104 10119856
18:26:26 47906 8192000000 10315914960 10773377024 1399618496 14.592 100 23 46 0 1417635264 12368400 5648368
18:28:15 31835 8192000000 10308155824 10770776064 1395034576 14.551 99 9 18 0 1407349696 10049680 2265440
18:29:48 48792 8192000000 10305428000 10768048128 1403065920 14.622 100 19 39 0 1415107040 7534096 4507024
18:31:46 73616 8192000000 10292145584 10759495680 1365146544 14.284 97 14 28 0 1374354976 6439680 2768752
18:34:08 32979 8192000000 10268641168 10727391232 1352084704 14.166 97 26 50 0 1366416096 8258032 6073360
18:36:05 59982 8192000000 10294387392 10767962112 1395242880 14.553 100 6 12 0 1406052832 9472864 1337088
18:38:13 53979 8192000000 10289590624 10742427648 1338930560 14.048 96 16 32 0 1353337920 10748416 3658944
18:40:07 60676 8192000000 10279540208 10742497280 1341854160 14.074 97 15 30 0 1359655088 14375536 3425392
18:42:12 45711 8192000000 10280642432 10737664000 1359388656 14.232 99 22 44 0 1378248928 13532320 5327952
18:44:01 34724 8192000000 10284104272 10717487104 1288086592 13.587 94 39 76 0 1310713936 13930880 8696464
18:45:36 28892 8192000000 10248895408 10696585216 1313569216 13.818 98 30 60 1 1335343776 15707584 6066976
18:47:20 72560 8192000000 10264712640 10714599424 1324284336 13.915 98 12 24 0 1340511856 13447744 2779776
18:49:40 63225 8192000000 10277574800 10708025344 1280453600 13.517 94 28 56 0 1296222880 9637184 6132096
18:51:45 136906 8192000000 10255262320 10694508544 1303689296 13.729 96 34 65 1 1323047792 11896928 7461568
18:55:03 64664 8192000000 10256033120 10714451968 1351373296 14.160 99 15 30 0 1365801280 10904432 3523552
18:57:17 49591 8192000000 10242420672 10698612736 1337639840 14.036 98 13 26 0 1355276176 14568080 3068256
18:59:28 60712 8192000000 10261234528 10716524544 1347171344 14.122 99 12 24 0 1362519440 12705696 2642400
19:01:28 40523 8192000000 10269490848 10723680256 1358431008 14.223 99 16 32 0 1373457760 11194816 3831936
19:03:24 74210 8192000000 10262430016 10736582656 1319903408 13.876 96 14 28 0 1338928592 15725584 3299600
19:05:39 37334 8192000000 10287987552 10734456832 1375197952 14.374 100 21 40 0 1388512336 8839584 4474800
19:07:17 47818 8192000000 10291680640 10744123392 1356445968 14.205 98 23 44 0 1370541728 9234976 4860784
19:09:17 35667 8192000000 10306450144 10754691072 1383532128 14.448 99 28 56 0 1403812464 14335488 5944848
19:10:53 73800 8192000000 10310135744 10763866112 1340209808 14.059 96 10 20 0 1355792432 13587808 1994816
19:13:07 64329 8192000000 10306182944 10761699328 1391617696 14.520 100 3 6 0 1404668560 12684048 366816
19:15:22 121274 8192000000 10316880672 10775412736 1396997616 14.568 99 29 59 0 1411593968 7887248 6709104
19:18:24 46511 8192000000 10331419216 10794074112 1414646272 14.725 100 17 34 0 1442364928 24009824 3708832
19:20:17 59294 8192000000 10318358416 10804146176 1414030528 14.720 100 9 18 0 1433308496 17242048 2035920
19:22:16 36930 8192000000 10308051632 10770018304 1409294416 14.678 100 23 46 0 1422428656 7837680 5296560
19:23:57 64034 8192000000 10305658352 10770173952 1404247552 14.633 100 18 36 1 1417930688 8646416 5036720
19:26:26 55759 8192000000 10307922928 10786758656 1392567232 14.529 99 6 12 0 1405426064 11772560 1086272
19:28:27 57018 8192000000 10266391808 10721501184 1286013488 13.568 92 11 22 0 1299155104 11086544 2055072
19:30:35 42489 8192000000 10296268320 10751090688 1358614624 14.225 97 23 46 0 1378485328 14136592 5734112
19:32:31 81461 8192000000 10281824880 10733215744 1292000128 13.622 93 18 34 0 1317544592 21562016 3982448
19:34:59 50290 8192000000 10278463584 10731945984 1326310432 13.934 97 15 30 0 1369725392 38891584 4523376
19:36:52 83079 8192000000 10276450160 10735128576 1292414128 13.626 95 8 16 0 1311118896 17188000 1516768

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-12-2022 19:39:33 to 06-12-2022 19:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 99 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-12-2022 19:39:49 to 06-12-2022 19:39:51.

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          242807
MON$OLDEST_ACTIVE               243631
MON$OLDEST_SNAPSHOT             243631
MON$NEXT_TRANSACTION            243631
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                       2065824
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.33646 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33646 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33646 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33646 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Dec  6 19:38:45 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Dec  6 19:38:45 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Dec  6 19:38:45 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
--- end of diff output ---

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

06.12.22 19:39:52. 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-12-06 19:39:55 No severe PSQL-related problems occured
06.12.22 19:39:56. End of report.