Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.04.2022 22: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 16549844 kB
MemAvailable 31594372 kB
Buffers 0 kB
Cached 15188356 kB
SwapCached 2272 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-06 22:38:15
DTS_END                         2022-04-06 22:38:15.9890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47630
TRN_ID                          250353
ATT_ID                          724
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: 9623.37 22457 1154804
customer order: creation 182.23 344 21867
customer order: refuse 15.17 315 1820
order to supplier: creation 50.09 1275 6011
order to supplier: removal 3.73 85976 448
invoice (draft): creation 48.85 3380 5862
invoice (draft): removal 53.39 757 6407
invoice accept: apply 45.46 16888 5455
invoice accept: cancel 48.23 796 5787
customer reserve: creation 4153.11 169 498373
customer reserve: removal 4711.47 52 565376
realization accept: apply 90.18 163 10821
realization accept: cancel 82.73 90 9928
payment from customer: creation 63.39 102 7607
payment from customer: removal 6.46 71 775
payment to supplier: creation 51.48 117 6178
payment to supplier: removal 3.70 62 444
service: total inventory turnovers 5.30 864 636
service: total monetary turnovers 7.40 22 888
service: aggregate perf. data 0.98 16382 117
service: refresh index statistics 0.03 321305 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-04-2022 22:40:22 to 06-04-2022 22:40:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2119
TEST_TIME 2 2637
TEST_TIME 3 3245
TEST_TIME 4 4275
TEST_TIME 5 4522
TEST_TIME 6 5108
TEST_TIME 7 5590
TEST_TIME 8 6002
TEST_TIME 9 6139
TEST_TIME 10 6578
TEST_TIME 11 6899
TEST_TIME 12 7409
TEST_TIME 13 7702
TEST_TIME 14 7870
TEST_TIME 15 7729
TEST_TIME 16 7639
TEST_TIME 17 7884
TEST_TIME 18 7795
TEST_TIME 19 8107
TEST_TIME 20 8777
TEST_TIME 21 9075
TEST_TIME 22 9487
TEST_TIME 23 9327
TEST_TIME 24 9441
TEST_TIME 25 9564
TEST_TIME 26 9485
TEST_TIME 27 9836
TEST_TIME 28 10160
TEST_TIME 29 10743
TEST_TIME 30 10529
TEST_TIME 31 10397
TEST_TIME 32 10237
TEST_TIME 33 10173
TEST_TIME 34 10039
TEST_TIME 35 10183
TEST_TIME 36 10525
TEST_TIME 37 10486
TEST_TIME 38 10316
TEST_TIME 39 10293
TEST_TIME 40 10292
TEST_TIME 41 10316
TEST_TIME 42 10219
TEST_TIME 43 10060
TEST_TIME 44 9892
TEST_TIME 45 9925
TEST_TIME 46 10001
TEST_TIME 47 9882
TEST_TIME 48 9908
TEST_TIME 49 9794
TEST_TIME 50 9895
TEST_TIME 51 9898
TEST_TIME 52 9840
TEST_TIME 53 9843
TEST_TIME 54 9833
TEST_TIME 55 9741
TEST_TIME 56 9717
TEST_TIME 57 9638
TEST_TIME 58 9689
TEST_TIME 59 9643
TEST_TIME 60 9609
TEST_TIME 61 9728
TEST_TIME 62 9725
TEST_TIME 63 9662
TEST_TIME 64 9910
TEST_TIME 65 9918
TEST_TIME 66 9876
TEST_TIME 67 9924
TEST_TIME 68 9899
TEST_TIME 69 9946
TEST_TIME 70 9978
TEST_TIME 71 9985
TEST_TIME 72 9957
TEST_TIME 73 9999
TEST_TIME 74 9941
TEST_TIME 75 9868
TEST_TIME 76 9870
TEST_TIME 77 9792
TEST_TIME 78 9851
TEST_TIME 79 9783
TEST_TIME 80 9735
TEST_TIME 81 9669
TEST_TIME 82 9685
TEST_TIME 83 9626
TEST_TIME 84 9590
TEST_TIME 85 9648
TEST_TIME 86 9640
TEST_TIME 87 9625
TEST_TIME 88 9680
TEST_TIME 89 9653
TEST_TIME 90 9655
TEST_TIME 91 9763
TEST_TIME 92 9782
TEST_TIME 93 9828
TEST_TIME 94 9822
TEST_TIME 95 9843
TEST_TIME 96 9868
TEST_TIME 97 9849
TEST_TIME 98 9851
TEST_TIME 99 9788
TEST_TIME 100 9736
TEST_TIME 101 9732
TEST_TIME 102 9724
TEST_TIME 103 9677
TEST_TIME 104 9639
TEST_TIME 105 9591
TEST_TIME 106 9588
TEST_TIME 107 9538
TEST_TIME 108 9568
TEST_TIME 109 9613
TEST_TIME 110 9610
TEST_TIME 111 9628
TEST_TIME 112 9614
TEST_TIME 113 9673
TEST_TIME 114 9687
TEST_TIME 115 9657
TEST_TIME 116 9687
TEST_TIME 117 9678
TEST_TIME 118 9712
TEST_TIME 119 9704
TEST_TIME 120 9624

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-04-2022 22:40:25 to 06-04-2022 22:40:29.

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 1615986 1615986 0 0.00 0 24000 6 0 0
doc_list_biud 1615986 1615986 0 0.00 0 23333 24 0 0
sp_add_invoice_to_stock 5582 5455 127 2.27 46 211974 16888 0 127
sp_cancel_adding_invoice 5787 5787 0 0.00 3 103232 797 0 0
sp_cancel_client_order 1820 1820 0 0.00 3 9731 316 0 0
sp_cancel_customer_reserve 565376 565376 0 0.00 1 22628 53 0 0
sp_cancel_pay_from_customer 775 775 0 0.00 3 8234 71 0 0
sp_cancel_pay_to_supplier 444 444 0 0.00 3 1727 63 0 0
sp_cancel_supplier_invoice 6420 6407 13 0.20 1 23342 758 0 13
sp_cancel_supplier_order 448 448 0 0.00 7 852977 85976 0 0
sp_cancel_write_off 9928 9928 0 0.00 1 10021 91 0 0
sp_client_order 21867 21867 0 0.00 3 24650 344 0 0
sp_customer_reserve 498373 498373 0 0.00 0 38910 169 0 0
sp_fill_shopping_cart 33740 33740 0 0.00 1 14479 35 0 0
sp_fill_shopping_cart_clo_res 498372 498372 0 0.00 0 11761 12 0 0
sp_get_clo_for_invoice 5454 5454 0 0.00 0 22388 251 0 0
sp_kill_cost_storno 16934 16934 0 0.00 0 5995 50 0 0
sp_kill_qstorno_ret_qs2qd 1147535 1147535 0 0.00 0 23332 29 0 0
sp_kill_qty_storno 606041 606041 0 0.00 0 23333 63 0 0
sp_lock_selected_doc 22367 22367 0 0.00 0 11780 6 0 0
sp_make_cost_storno 30060 30060 0 0.00 0 23998 52 0 0
sp_multiply_rows_for_pdistr 30060 30060 0 0.00 0 6991 27 0 0
sp_multiply_rows_for_qdistr 21867 21867 0 0.00 0 13327 152 0 0
sp_pay_from_customer 7607 7607 0 0.00 4 12435 103 0 0
sp_pay_to_supplier 6178 6178 0 0.00 5 21324 118 0 0
sp_payment_common 13785 13785 0 0.00 2 10274 23 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 9722 300 0 0
sp_qd_handle_on_invoice_upd_sts 11241 11241 0 0.00 0 22872 335 0 0
sp_qd_handle_on_reserve_upd_sts 10821 10821 0 0.00 0 9647 50 0 0
sp_reserve_write_off 10821 10821 0 0.00 6 24024 164 0 0
sp_supplier_invoice 5862 5862 0 0.00 5 105806 3380 0 0
sp_supplier_order 6011 6011 0 0.00 5 74502 1275 0 0
srv_aggregate_perf_data 117 117 0 0.00 2266 158131 16383 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2491 137452 44660 0 0
srv_find_qd_qs_mism 1103512 1103512 0 0.00 0 15566 5 0 0
srv_make_invnt_saldo 636 636 0 0.00 41 19507 865 0 0
srv_make_money_saldo 888 888 0 0.00 1 1120 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 270939 364334 321305 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 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 5455 5455 0 0.00 0 9523 144 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 3203 85 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 2948 16 0 0
t$perf-norm:sp_cancel_customer_reserve 723 723 0 0.00 0 2424 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 775 775 0 0.00 0 1738 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 444 444 0 0.00 0 771 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 434 434 0 0.00 0 6199 31 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 27907 1409 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 2681 8 0 0
t$perf-norm:sp_client_order 21867 21867 0 0.00 0 9232 17 0 0
t$perf-norm:sp_customer_reserve 1135 1135 0 0.00 0 3180 9 0 0
t$perf-norm:sp_pay_from_customer 7607 7607 0 0.00 0 17906 13 0 0
t$perf-norm:sp_pay_to_supplier 6178 6178 0 0.00 0 4001 8 0 0
t$perf-norm:sp_reserve_write_off 10822 10822 0 0.00 0 20135 13 0 0
t$perf-norm:sp_supplier_invoice 5862 5862 0 0.00 0 5592 16 0 0
t$perf-norm:sp_supplier_order 6011 6011 0 0.00 0 2298 14 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 3084 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 45 2 0 0
t$perf-norm:srv_make_invnt_saldo 636 636 0 0.00 0 1685 23 0 0
t$perf-norm:srv_make_money_saldo 888 888 0 0.00 0 2066 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 11160 10906 254 2.27 0 2717 15 0 254
v_all_customers 39348 39348 0 0.00 0 22 1 0 0
v_all_suppliers 32168 32168 0 0.00 0 103 1 0 0
v_all_wares 43734 43734 0 0.00 0 46 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 273 5 0 0
v_cancel_client_order 3640 3640 0 0.00 0 1077 2 0 0
v_cancel_customer_prepayment 1550 1550 0 0.00 0 4344 8 0 0
v_cancel_customer_reserve 1446 1446 0 0.00 0 1720 5 0 0
v_cancel_payment_to_supplier 888 888 0 0.00 0 1391 6 0 0
v_cancel_supplier_invoice 894 868 26 2.90 0 4370 19 0 26
v_cancel_supplier_order 896 896 0 0.00 0 3475 9 0 0
v_cancel_write_off 2062 2062 0 0.00 0 3107 14 0 0
v_max_id_clo_ord 6011 6011 0 0.00 0 450 1 0 0
v_max_id_clo_res 1135 1135 0 0.00 0 2656 12 0 0
v_max_id_ord_sup 5862 5862 0 0.00 0 4752 4 0 0
v_max_non_paid_invoice 1967 1967 0 0.00 0 791 3 0 0
v_max_non_paid_realizn 4299 4299 0 0.00 0 1092 3 0 0
v_min_id_clo_ord 6011 6011 0 0.00 0 5206 7 0 0
v_min_id_clo_res 1135 1135 0 0.00 0 572 2 0 0
v_min_id_ord_sup 5862 5862 0 0.00 0 4634 4 0 0
v_min_non_paid_invoice 6178 6178 0 0.00 0 10271 15 0 0
v_min_non_paid_realizn 7607 7607 0 0.00 0 6973 18 0 0
v_our_firm 11002 11002 0 0.00 0 157 1 0 0
v_reserve_write_off 21642 21642 0 0.00 0 6180 5 0 0
x_lock_depdocs_on_canc_invoice 11760 11760 0 0.00 0 15737 115 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 16521 406 0 0
x_make_qstorno_1000_1200 6011 6011 0 0.00 2 74448 1192 0 0
x_make_qstorno_1200_2000 5862 5862 0 0.00 2 99664 3264 0 0
x_make_qstorno_2100_3300 498373 498373 0 0.00 0 33925 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-04-2022 22:40:30 to 06-04-2022 22:40:34.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:19 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:20 36292 8192000000 9923371728 10236493824 996056336 10.840 100 90 180 0 1025166496 6261760 22848400
20:11:01 10912 8192000000 9953250688 10274689024 1034575856 11.212 100 92 184 1 1067312560 9265392 23471312
20:12:20 19569 8192000000 9982638960 10314293248 1063789600 11.493 100 82 164 1 1094412896 10567712 20055584
20:13:46 11413 8192000000 10043796144 10394812416 1132801040 12.148 100 80 160 1 1162658720 10985104 18872576
20:15:05 7115 8192000000 10099242736 10461339648 1185941840 12.646 100 83 166 1 1218661760 13744240 18975680
20:16:14 5678 8192000000 10138640064 10505777152 1219414752 12.956 100 85 171 4 1251888656 13431504 19042400
20:17:21 3399 8192000000 10172424736 10545442816 1255278688 13.287 100 95 188 1 1290197328 14469872 20448768
20:18:26 2125 8192000000 10205085936 10584190976 1283248256 13.543 100 95 191 3 1319137744 14481328 21408160
20:19:29 1819 8192000000 10222143280 10604429312 1302261136 13.716 100 91 186 3 1336275904 13546528 20468240
20:20:32 6710 8192000000 10195772640 10607734784 1306443216 13.754 100 29 58 0 1320436816 7573024 6420576
20:21:43 5313 8192000000 10203438800 10619383808 1316284672 13.843 100 21 42 0 1327077296 5944112 4848512
20:22:51 9791 8192000000 10209346528 10626457600 1324002528 13.913 100 17 34 1 1334105504 6758800 3344176
20:24:01 57260 8192000000 10235613360 10642583552 1326207296 13.933 100 66 133 3 1348470096 5855968 16406832
20:25:58 5155 8192000000 10223647168 10637803520 1328105760 13.950 100 12 25 0 1335772896 5156544 2510592
20:27:04 4093 8192000000 10220148704 10639491072 1328290688 13.952 100 31 59 2 1341506512 5287520 7928304
20:28:09 50508 8192000000 10178043776 10592882688 1202213904 12.797 91 36 71 2 1216477680 6444272 7819504
20:30:00 5535 8192000000 10170812720 10574888960 1267963088 13.403 99 25 50 1 1279893248 5924784 6005376
20:31:09 4869 8192000000 10163933280 10558906368 1261295920 13.342 100 27 53 0 1273595488 6049584 6249984
20:32:15 62697 8192000000 10147888336 10548637696 1216643744 12.931 96 45 91 1 1232833744 5755744 10434256
20:34:20 7863 8192000000 10176873504 10581307392 1258671056 13.318 98 29 57 0 1271298528 5683504 6943968
20:35:29 7953 8192000000 10187756864 10600669184 1295923120 13.658 100 22 44 0 1307949808 6814384 5212304
20:36:39 23059 8192000000 10193341840 10609078272 1301157600 13.706 100 13 26 1 1309618048 5327136 3133312
20:38:03 20182 8192000000 10208962784 10623553536 1309269936 13.779 100 19 38 1 1318617552 4537808 4809808
20:39:30 47555 8192000000 10245363280 10656993280 1338774688 14.046 100 44 88 2 1359243792 10877456 9591648
20:41:22 31146 8192000000 10258414992 10678841344 1354026992 14.184 100 37 74 1 1373616528 11020224 8569312
20:43:02 10021 8192000000 10276506112 10696454144 1368321936 14.312 100 46 92 1 1389669264 10845440 10501888
20:44:21 7454 8192000000 10295272576 10717425664 1380694288 14.423 100 47 93 1 1406303072 14322208 11286576
20:45:34 14951 8192000000 10298329744 10730741760 1389054592 14.497 100 45 90 1 1416531968 16316288 11161088
20:47:29 2495 8192000000 10324920400 10740920320 1397106432 14.569 100 67 133 1 1429008944 16081312 15821200
20:48:35 7755 8192000000 10322991984 10743521280 1396939168 14.568 100 66 132 2 1431621536 18704768 15977600
20:49:46 4738 8192000000 10331879840 10757853184 1395537680 14.555 99 63 126 1 1432712144 21392960 15781504
20:50:54 4547 8192000000 10325411344 10742722560 1383262144 14.446 99 68 136 0 1424686336 24879056 16545136
20:52:06 2489 8192000000 10318444224 10740015104 1383962880 14.452 99 73 147 2 1426110256 23715040 18432336
20:53:13 9826 8192000000 10330649376 10754777088 1393946400 14.541 99 55 110 0 1433344224 26133936 13263888
20:54:28 3280 8192000000 10326792192 10747265024 1383849920 14.451 99 63 124 0 1428201792 29027280 15324592
20:55:36 3656 8192000000 10345490768 10759155712 1389044576 14.497 99 84 166 3 1444333504 35447856 19841072
20:56:45 4253 8192000000 10345679376 10759876608 1386863184 14.478 100 77 154 2 1450652192 43706928 20082080
20:57:56 10053 8192000000 10350841616 10771976192 1388711888 14.494 100 53 106 1 1450903312 47944480 14246944
20:59:13 11889 8192000000 10320967008 10749308928 1371246160 14.338 99 37 72 2 1423796416 43169808 9380448
21:00:31 9442 8192000000 10321075632 10749009920 1369959264 14.327 99 42 84 0 1418298448 37579280 10759904
21:01:44 18259 8192000000 10308323104 10739331072 1363660816 14.270 99 34 68 4 1407314336 34190448 9463072
21:03:09 24146 8192000000 10307167824 10745237504 1369282320 14.321 100 22 44 0 1416609824 41409376 5918128
21:04:40 9899 8192000000 10304761184 10732552192 1350746080 14.154 99 51 100 0 1401160512 37749872 12664560
21:06:25 22857 8192000000 10282794160 10737524736 1352061488 14.166 99 14 28 1 1385359504 29460640 3837376
21:08:29 77497 8192000000 10276518416 10705936384 1301493744 13.709 95 13 26 1 1312528048 7975776 3058528
21:10:55 78530 8192000000 10285269888 10717376512 1331517904 13.981 97 28 56 1 1353870560 16529392 5823264
21:13:28 56981 8192000000 10309520064 10758631424 1380564864 14.422 100 7 14 0 1407657184 24836848 2255472
21:15:29 51096 8192000000 10298139088 10738507776 1386299872 14.473 100 24 48 2 1401012080 9648304 5063904
21:17:27 25380 8192000000 10297232832 10743648256 1388149792 14.489 100 29 57 0 1403120752 8820912 6150048
21:18:56 85821 8192000000 10290194912 10740248576 1335765776 14.019 96 17 34 0 1349392912 10043744 3583392
21:21:28 77381 8192000000 10308373984 10748674048 1372309456 14.348 99 30 60 0 1391663408 11704448 7649504
21:23:52 25982 8192000000 10314085840 10764316672 1400507584 14.600 100 44 88 0 1421488288 10990832 9989872
21:26:43 32508 8192000000 10295371808 10751217664 1383646656 14.449 99 21 42 0 1395018000 6941616 4429728
21:28:19 33060 8192000000 10293019872 10759680000 1380988640 14.425 99 32 66 1 1400383920 11890304 7504976
21:29:59 31833 8192000000 10278722656 10742693888 1364407744 14.277 99 6 12 0 1378034208 12209232 1417232
21:32:31 43074 8192000000 10280509760 10730758144 1360316032 14.240 99 19 39 0 1374894944 9846336 4732576
21:34:17 86731 8192000000 10278846160 10733924352 1351065792 14.157 98 15 29 1 1368683472 14403328 3214352
21:36:48 33356 8192000000 10271775840 10729279488 1349278800 14.141 98 25 50 0 1369117200 14298384 5540016
21:38:26 71899 8192000000 10288433376 10747994112 1362629456 14.261 98 13 26 1 1378740208 12824640 3286112
21:40:38 39606 8192000000 10303978848 10750562304 1359645936 14.234 98 27 54 0 1374722144 8682592 6393616
21:42:20 43647 8192000000 10284598352 10748219392 1360131904 14.239 98 11 22 1 1379754336 17395456 2226976
21:44:04 46226 8192000000 10303099152 10745491456 1351878512 14.164 97 19 38 0 1364267440 8138624 4250304
21:45:55 30328 8192000000 10304490928 10749718528 1377080272 14.390 99 42 82 1 1394670896 8505408 9085216
21:47:26 56523 8192000000 10283568880 10726563840 1335207536 14.014 97 15 30 1 1354068352 15126272 3734544
21:49:23 56345 8192000000 10272290160 10716676096 1332839488 13.993 97 23 46 1 1349482000 11629632 5012880
21:51:20 106553 8192000000 10276756624 10741645312 1353834832 14.182 98 12 24 0 1367869360 11181392 2853136
21:54:46 54499 8192000000 10269402128 10717417472 1340581776 14.063 98 30 60 1 1358600352 11583520 6435056
21:56:42 66512 8192000000 10271761168 10730598400 1372556960 14.350 100 10 19 1 1382629600 8406832 1665808
21:58:54 53965 8192000000 10293111808 10744856576 1369956112 14.327 99 16 33 1 1385002544 10860624 4185808
22:01:46 55815 8192000000 10266533696 10730803200 1349938464 14.147 98 19 38 1 1368720272 14211008 4570800
22:03:42 53076 8192000000 10264904432 10721263616 1339562880 14.053 98 13 26 1 1354359184 12097792 2698512
22:05:36 40490 8192000000 10282826752 10742534144 1378074192 14.399 100 31 60 0 1395456320 11157664 6224464
22:07:29 53791 8192000000 10321120320 10774941696 1395878912 14.558 100 24 48 3 1421537120 20138480 5519728
22:09:47 85299 8192000000 10296269424 10744459264 1327779344 13.947 95 14 28 0 1348361264 17673856 2908064
22:13:19 69123 8192000000 10295192400 10752925696 1347371712 14.124 97 7 14 1 1358089616 9372672 1345232
22:15:39 15920 8192000000 10294073632 10734206976 1364152144 14.275 99 51 100 3 1385367472 8572384 12642944
22:16:56 137452 8192000000 10296409296 10761961472 1393285872 14.535 100 22 44 2 1408846176 10608368 4951936
22:20:15 54751 8192000000 10262202016 10731171840 1331724544 13.983 96 10 20 1 1340917520 6904704 2288272
22:22:12 109118 8192000000 10270387296 10737262592 1349405632 14.142 97 10 20 0 1359741360 8039392 2296336
22:25:13 55651 8192000000 10298815152 10753810432 1350137440 14.149 97 19 38 1 1366689136 12034864 4516832
22:27:12 86388 8192000000 10275217376 10744180736 1329052320 13.959 96 14 28 1 1343885600 11555248 3278032
22:29:44 73280 8192000000 10294643552 10754715648 1360217552 14.239 98 21 42 1 1376229232 10879264 5132416
22:31:57 97178 8192000000 10284104912 10742161408 1302182912 13.715 95 10 20 0 1320431104 16177968 2070224
22:34:35 51655 8192000000 10294537600 10748600320 1354038016 14.184 98 16 31 1 1374223840 16585328 3600496
22:36:28 128258 8192000000 10020279200 10444939264 1066556816 11.519 77 5 10 0 1079841120 12354560 929744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 06-04-2022 22:40:36 to 06-04-2022 22:40:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 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-04-2022 22:40:51 to 06-04-2022 22:40:53.

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          250653
MON$OLDEST_ACTIVE               250654
MON$OLDEST_SNAPSHOT             250654
MON$NEXT_TRANSACTION            250654
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                       2049696
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.33576 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33576 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33576 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33576 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 ---
:44: 
:45: mscs-test-161.budzdorov.ru    Wed Apr  6 22:39:05 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Wed Apr  6 22:39:06 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
--- end of diff output ---

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

06.04.22 22:40:54. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-06 22:40:57 No severe PSQL-related problems occured
06.04.22 22:40:58. End of report.