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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16304480 kB
MemAvailable 31504236 kB
Buffers 0 kB
Cached 15423240 kB
SwapCached 2616 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-05-10 22:38:42
DTS_END                         2022-05-10 22:38:12.5080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54194
TRN_ID                          250708
ATT_ID                          274
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: 9401.46 22078 1128175
customer order: creation 179.10 348 21492
customer order: refuse 15.50 336 1860
order to supplier: creation 46.77 1218 5612
order to supplier: removal 3.18 112970 381
invoice (draft): creation 47.43 3264 5691
invoice (draft): removal 52.07 803 6248
invoice accept: apply 44.83 18341 5380
invoice accept: cancel 47.18 793 5661
customer reserve: creation 4047.32 187 485678
customer reserve: removal 4617.91 62 554149
realization accept: apply 87.12 170 10454
realization accept: cancel 79.64 109 9557
payment from customer: creation 60.40 104 7248
payment from customer: removal 5.82 69 698
payment to supplier: creation 51.02 118 6122
payment to supplier: removal 3.38 60 406
service: total inventory turnovers 4.96 1248 595
service: total monetary turnovers 6.92 28 830
service: aggregate perf. data 0.91 21342 109
service: refresh index statistics 0.03 279994 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-05-2022 22:40:05 to 10-05-2022 22:40:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5143
TEST_TIME 2 5697
TEST_TIME 3 5868
TEST_TIME 4 6188
TEST_TIME 5 6860
TEST_TIME 6 6978
TEST_TIME 7 7323
TEST_TIME 8 7155
TEST_TIME 9 7256
TEST_TIME 10 7676
TEST_TIME 11 7748
TEST_TIME 12 7553
TEST_TIME 13 7405
TEST_TIME 14 7363
TEST_TIME 15 7336
TEST_TIME 16 7660
TEST_TIME 17 7860
TEST_TIME 18 8116
TEST_TIME 19 7998
TEST_TIME 20 8111
TEST_TIME 21 8431
TEST_TIME 22 8739
TEST_TIME 23 8891
TEST_TIME 24 9062
TEST_TIME 25 9553
TEST_TIME 26 9972
TEST_TIME 27 10104
TEST_TIME 28 10971
TEST_TIME 29 11050
TEST_TIME 30 10808
TEST_TIME 31 10807
TEST_TIME 32 10653
TEST_TIME 33 10591
TEST_TIME 34 10552
TEST_TIME 35 10476
TEST_TIME 36 10367
TEST_TIME 37 10218
TEST_TIME 38 10148
TEST_TIME 39 10167
TEST_TIME 40 10090
TEST_TIME 41 10039
TEST_TIME 42 9920
TEST_TIME 43 9820
TEST_TIME 44 9759
TEST_TIME 45 10077
TEST_TIME 46 9939
TEST_TIME 47 9972
TEST_TIME 48 10088
TEST_TIME 49 10053
TEST_TIME 50 10041
TEST_TIME 51 10080
TEST_TIME 52 10084
TEST_TIME 53 9989
TEST_TIME 54 10207
TEST_TIME 55 10146
TEST_TIME 56 10131
TEST_TIME 57 10165
TEST_TIME 58 10160
TEST_TIME 59 10253
TEST_TIME 60 10172
TEST_TIME 61 10181
TEST_TIME 62 10170
TEST_TIME 63 10126
TEST_TIME 64 10058
TEST_TIME 65 9970
TEST_TIME 66 9958
TEST_TIME 67 9929
TEST_TIME 68 9889
TEST_TIME 69 9922
TEST_TIME 70 9863
TEST_TIME 71 9795
TEST_TIME 72 9743
TEST_TIME 73 9656
TEST_TIME 74 9742
TEST_TIME 75 9714
TEST_TIME 76 9702
TEST_TIME 77 9659
TEST_TIME 78 9632
TEST_TIME 79 9643
TEST_TIME 80 9632
TEST_TIME 81 9652
TEST_TIME 82 9662
TEST_TIME 83 9763
TEST_TIME 84 9766
TEST_TIME 85 9778
TEST_TIME 86 9744
TEST_TIME 87 9842
TEST_TIME 88 9897
TEST_TIME 89 9852
TEST_TIME 90 9827
TEST_TIME 91 9764
TEST_TIME 92 9720
TEST_TIME 93 9745
TEST_TIME 94 9700
TEST_TIME 95 9650
TEST_TIME 96 9625
TEST_TIME 97 9592
TEST_TIME 98 9669
TEST_TIME 99 9651
TEST_TIME 100 9644
TEST_TIME 101 9626
TEST_TIME 102 9657
TEST_TIME 103 9640
TEST_TIME 104 9603
TEST_TIME 105 9700
TEST_TIME 106 9754
TEST_TIME 107 9763
TEST_TIME 108 9726
TEST_TIME 109 9792
TEST_TIME 110 9818
TEST_TIME 111 9732
TEST_TIME 112 9689
TEST_TIME 113 9644
TEST_TIME 114 9625
TEST_TIME 115 9583
TEST_TIME 116 9553
TEST_TIME 117 9528
TEST_TIME 118 9496
TEST_TIME 119 9461
TEST_TIME 120 9404

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-05-2022 22:40:09 to 10-05-2022 22:40:13.

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 1576311 1576311 0 0.00 0 12486 7 0 0
doc_list_biud 1576310 1576310 0 0.00 0 70874 27 0 0
sp_add_invoice_to_stock 5497 5380 117 2.12 15 191931 18341 0 117
sp_cancel_adding_invoice 5661 5661 0 0.00 5 71365 794 0 0
sp_cancel_client_order 1860 1860 0 0.00 5 8364 337 0 0
sp_cancel_customer_reserve 554149 554149 0 0.00 1 70876 62 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 2 7234 70 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 2 1261 61 0 0
sp_cancel_supplier_invoice 6252 6248 4 0.06 2 48023 803 0 4
sp_cancel_supplier_order 381 381 0 0.00 11 1111637 112971 0 0
sp_cancel_write_off 9557 9557 0 0.00 1 10602 109 0 0
sp_client_order 21492 21492 0 0.00 3 50299 349 0 0
sp_customer_reserve 485678 485678 0 0.00 0 69008 187 0 0
sp_fill_shopping_cart 32792 32792 0 0.00 1 13208 36 0 0
sp_fill_shopping_cart_clo_res 485674 485674 0 0.00 0 23827 13 0 0
sp_get_clo_for_invoice 5375 5375 0 0.00 0 35357 298 0 0
sp_kill_cost_storno 16322 16322 0 0.00 0 10419 53 0 0
sp_kill_qstorno_ret_qs2qd 1124484 1124484 0 0.00 0 70872 33 0 0
sp_kill_qty_storno 593684 593684 0 0.00 0 70874 72 0 0
sp_lock_selected_doc 21699 21699 0 0.00 0 10623 5 0 0
sp_make_cost_storno 29198 29198 0 0.00 0 12254 54 0 0
sp_multiply_rows_for_pdistr 29198 29198 0 0.00 0 7941 24 0 0
sp_multiply_rows_for_qdistr 21492 21492 0 0.00 0 39562 168 0 0
sp_pay_from_customer 7248 7248 0 0.00 4 12794 105 0 0
sp_pay_to_supplier 6122 6122 0 0.00 4 18003 118 0 0
sp_payment_common 13370 13370 0 0.00 1 17988 23 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 8353 301 0 0
sp_qd_handle_on_invoice_upd_sts 11035 11035 0 0.00 0 21347 310 0 0
sp_qd_handle_on_reserve_upd_sts 10454 10454 0 0.00 0 13413 58 0 0
sp_reserve_write_off 10454 10454 0 0.00 6 13426 170 0 0
sp_supplier_invoice 5691 5691 0 0.00 7 70097 3265 0 0
sp_supplier_order 5612 5612 0 0.00 4 30721 1218 0 0
srv_aggregate_perf_data 109 109 0 0.00 3098 602698 21343 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2408 139330 44805 0 0
srv_find_qd_qs_mism 1077763 1077763 0 0.00 0 12484 6 0 0
srv_make_invnt_saldo 595 595 0 0.00 53 75521 1249 0 0
srv_make_money_saldo 830 830 0 0.00 1 4547 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 165897 589784 279995 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5380 5380 0 0.00 0 10439 179 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 2910 97 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 6953 17 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 964 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 7345 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 5046 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 798 14 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 22675 1624 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 670 5 0 0
t$perf-norm:sp_client_order 21492 21492 0 0.00 0 9001 18 0 0
t$perf-norm:sp_customer_reserve 1080 1080 0 0.00 0 9467 12 0 0
t$perf-norm:sp_pay_from_customer 7248 7248 0 0.00 0 8135 10 0 0
t$perf-norm:sp_pay_to_supplier 6122 6122 0 0.00 0 9650 17 0 0
t$perf-norm:sp_reserve_write_off 10454 10454 0 0.00 0 8134 14 0 0
t$perf-norm:sp_supplier_invoice 5691 5691 0 0.00 0 6455 20 0 0
t$perf-norm:sp_supplier_order 5612 5612 0 0.00 0 4090 15 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1048 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 233 5 0 0
t$perf-norm:srv_make_invnt_saldo 595 595 0 0.00 0 2098 20 0 0
t$perf-norm:srv_make_money_saldo 830 830 0 0.00 0 1872 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10982 10748 234 2.13 0 4603 16 0 234
v_all_customers 38784 38784 0 0.00 0 22 1 0 0
v_all_suppliers 31226 31226 0 0.00 0 17 1 0 0
v_all_wares 42984 42984 0 0.00 0 87 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 802 6 0 0
v_cancel_client_order 3720 3720 0 0.00 0 4923 11 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 1071 4 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 4450 14 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 454 3 0 0
v_cancel_supplier_invoice 866 858 8 0.92 0 1686 18 0 8
v_cancel_supplier_order 762 762 0 0.00 0 194 3 0 0
v_cancel_write_off 2014 2014 0 0.00 0 2910 14 0 0
v_max_id_clo_ord 5610 5610 0 0.00 0 478 1 0 0
v_max_id_clo_res 1080 1080 0 0.00 0 10666 22 0 0
v_max_id_ord_sup 5690 5690 0 0.00 0 3959 4 0 0
v_max_non_paid_invoice 1809 1809 0 0.00 0 2117 4 0 0
v_max_non_paid_realizn 3986 3986 0 0.00 0 3388 5 0 0
v_min_id_clo_ord 5610 5610 0 0.00 0 6235 7 0 0
v_min_id_clo_res 1080 1080 0 0.00 0 4873 12 0 0
v_min_id_ord_sup 5690 5690 0 0.00 0 4145 5 0 0
v_min_non_paid_invoice 6122 6122 0 0.00 0 17984 15 0 0
v_min_non_paid_realizn 7248 7248 0 0.00 0 12642 15 0 0
v_our_firm 10724 10724 0 0.00 0 27 1 0 0
v_reserve_write_off 20908 20908 0 0.00 0 7313 6 0 0
x_lock_depdocs_on_canc_invoice 11480 11480 0 0.00 0 8012 115 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 7311 353 0 0
x_make_qstorno_1000_1200 5612 5612 0 0.00 1 30691 1137 0 0
x_make_qstorno_1200_2000 5691 5691 0 0.00 3 64791 3141 0 0
x_make_qstorno_2100_3300 485678 485678 0 0.00 0 69005 171 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-05-2022 22:40:13 to 10-05-2022 22:40:18.

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:16 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:16 40996 8192000000 9919313680 10230927360 986675136 10.749 100 88 175 0 1016201232 6618112 22907984
20:11:05 25369 8192000000 9956775360 10280476672 1036127808 11.227 100 87 174 0 1067582096 9464928 21989360
20:12:35 19765 8192000000 9993091680 10327851008 1076616528 11.615 100 81 162 0 1107085456 10702480 19766448
20:13:59 12573 8192000000 10042456432 10391900160 1128972112 12.112 100 72 144 1 1156417888 10690128 16755648
20:15:13 8543 8192000000 10088358352 10451337216 1175983728 12.553 100 83 164 0 1207384224 12868016 18532480
20:16:24 5255 8192000000 10138438368 10511818752 1224437008 13.003 100 73 146 1 1253331568 12873168 16021392
20:17:31 2402 8192000000 10163352736 10542804992 1261342080 13.342 100 81 162 1 1291506640 12286480 17878080
20:18:40 2071 8192000000 10203529552 10586255360 1283419216 13.544 100 95 188 3 1318281904 15003008 19859680
20:19:43 1395 8192000000 10213562032 10602926080 1303328640 13.725 100 71 140 2 1329901552 10256928 16315984
20:20:45 8923 8192000000 10199040992 10609303552 1309992240 13.786 100 30 60 1 1323729312 7169824 6567248
20:21:54 5185 8192000000 10202512480 10620076032 1316909152 13.849 100 26 52 1 1333478880 11049856 5519872
20:23:00 9143 8192000000 10211804336 10630017024 1325279744 13.924 100 23 46 1 1336222880 5812272 5130864
20:24:11 62276 8192000000 10225378912 10648449024 1332817408 13.993 100 24 48 0 1344101872 5582176 5702288
20:26:15 8122 8192000000 10225208272 10644987904 1320234624 13.879 99 27 53 2 1331250400 4882704 6133072
20:27:25 6321 8192000000 10222255408 10644660224 1331279072 13.979 100 26 53 3 1343839232 5114352 7445808
20:28:31 14947 8192000000 10179802560 10590887936 1249761904 13.236 95 19 38 0 1258601984 4760864 4079216
20:29:46 9428 8192000000 10158330944 10567806976 1231550720 13.068 96 20 40 0 1242601040 6844688 4205632
20:30:56 5719 8192000000 10146939472 10546343936 1221426992 12.975 97 27 54 3 1234240640 6397360 6416288
20:32:03 6338 8192000000 10161206576 10566385664 1259363824 13.324 99 29 58 0 1273010736 6212192 7434720
20:33:11 3634 8192000000 10174514336 10574893056 1268078688 13.404 99 34 68 1 1281911520 5907856 7924976
20:34:15 31699 8192000000 10168826640 10587176960 1279112096 13.505 99 24 46 1 1290424608 5495568 5816944
20:35:47 5711 8192000000 10200494912 10615382016 1307752512 13.766 100 19 38 0 1317435200 5204240 4478448
20:36:53 5005 8192000000 10204864016 10622832640 1316918656 13.849 100 28 55 1 1329268464 6349472 6000336
20:38:02 10635 8192000000 10218738720 10638450688 1329859120 13.966 100 17 34 0 1339134448 5449104 3826224
20:39:17 74083 8192000000 10250051088 10671685632 1339465120 14.053 99 47 94 1 1358969184 9316368 10187696
20:41:37 11838 8192000000 10277685248 10695290880 1367601200 14.306 100 56 112 0 1391801200 11119248 13080752
20:42:53 14663 8192000000 10294230288 10710515712 1372632240 14.351 100 54 107 2 1399863920 15666912 11564768
20:44:12 16465 8192000000 10286496752 10697908224 1365721616 14.289 99 64 126 0 1395107200 15342608 14042976
20:45:40 3601 8192000000 10327460288 10738262016 1394888432 14.549 100 80 162 2 1431459024 17102096 19468496
20:46:46 6711 8192000000 10316723984 10738610176 1385433104 14.465 99 57 112 2 1417166000 18649136 13083760
20:47:55 5700 8192000000 10317028000 10741059584 1392269792 14.526 100 67 134 2 1432027840 23445920 16312128
20:49:07 4724 8192000000 10300995280 10715107328 1374080144 14.364 100 70 140 3 1412746704 22796880 15869680
20:50:17 4483 8192000000 10291786288 10700984320 1345317280 14.105 97 67 134 1 1383067600 21516960 16233360
20:51:29 6531 8192000000 10318594672 10728517632 1379548064 14.413 100 59 118 1 1426204848 31795968 14860816
20:52:42 2948 8192000000 10329972544 10729902080 1378328624 14.402 100 80 158 2 1430304240 32184480 19791136
20:53:53 8697 8192000000 10312163728 10711408640 1361724480 14.253 100 73 146 2 1419833376 39080336 19028560
20:55:13 5780 8192000000 10319463856 10721210368 1362608048 14.261 100 67 130 0 1422469456 42238736 17622672
20:56:23 2406 8192000000 10327468192 10722299904 1360311072 14.240 100 93 186 3 1428033312 44630000 23092240
20:57:29 7134 8192000000 10306364816 10718715904 1360315248 14.240 100 47 94 1 1414129648 41430080 12384320
20:58:43 4598 8192000000 10330487920 10732875776 1369071584 14.319 100 84 168 0 1438764032 48290320 21402128
20:59:53 15826 8192000000 10324537136 10756022272 1374926256 14.371 100 37 74 1 1435782544 50489184 10367104
21:01:15 8464 8192000000 10309374176 10729140224 1362377568 14.259 100 48 96 1 1416983504 41527200 13078736
21:02:34 12168 8192000000 10315118448 10734583808 1356457072 14.206 100 39 77 1 1413853888 46768672 10628144
21:03:47 27430 8192000000 10301897216 10746998784 1365017792 14.282 99 29 58 2 1428000736 54704592 8278352
21:05:16 24349 8192000000 10275183488 10703220736 1314756512 13.829 98 19 37 1 1352026032 31360064 5909456
21:06:42 22745 8192000000 10263783088 10707533824 1335193456 14.014 99 8 16 0 1353946976 16776448 1977072
21:08:17 71875 8192000000 10285176432 10708172800 1309434864 13.781 96 27 54 2 1329909760 14459248 6015648
21:10:30 34209 8192000000 10295665744 10733826048 1384998720 14.461 100 40 80 2 1400319824 6627280 8693824
21:12:05 48902 8192000000 10307006080 10748141568 1376472112 14.385 99 17 34 0 1388552288 8475536 3604640
21:13:55 133571 8192000000 10312735632 10758402048 1398845456 14.585 100 30 57 0 1415447440 9970400 6631584
21:17:11 71595 8192000000 10327726368 10779062272 1412052384 14.702 100 22 44 0 1431242144 14078080 5111680
21:19:26 8642 8192000000 10344127984 10794921984 1432102624 14.880 100 38 76 0 1456845472 15358336 9384512
21:20:46 58612 8192000000 10354274016 10802905088 1431580368 14.875 100 35 69 3 1457571200 17523856 8466976
21:23:03 66412 8192000000 10327623296 10796105728 1405204704 14.641 99 21 42 2 1437991696 26978784 5808208
21:25:33 38541 8192000000 10315323200 10774233088 1382814672 14.442 98 20 40 0 1407288352 19620864 4852816
21:27:33 51714 8192000000 10296564736 10765881344 1394016784 14.542 100 8 16 1 1408453520 12485280 1951456
21:29:35 69170 8192000000 10287785376 10743201792 1342012144 14.076 96 16 32 0 1359537744 13622640 3902960
21:31:46 66536 8192000000 10289496272 10761285632 1381851536 14.433 99 24 48 1 1407736688 20292800 5592352
21:34:01 29283 8192000000 10293982064 10748907520 1378330016 14.402 99 14 28 2 1390550768 8950160 3270592
21:35:38 61226 8192000000 10319702704 10763776000 1376555648 14.386 99 11 22 0 1394637040 15712624 2368768
21:37:39 47635 8192000000 10290049776 10720546816 1326038784 13.931 95 30 58 1 1341341984 8366032 6937168
21:39:30 46282 8192000000 10287435024 10735595520 1345686112 14.109 98 35 70 2 1368563152 15496784 7380256
21:41:34 62865 8192000000 10282292080 10728906752 1351704208 14.163 98 20 40 0 1374589728 18392976 4492544
21:43:38 13775 8192000000 10280467744 10730008576 1376002496 14.381 100 32 64 0 1394727264 12184544 6540224
21:44:54 71627 8192000000 10270066304 10739032064 1359655760 14.234 99 12 23 0 1375851888 13624800 2571328
21:47:06 73405 8192000000 10247400592 10711261184 1312009296 13.804 96 9 18 0 1328067152 14226560 1831296
21:49:21 107454 8192000000 10270600672 10730156032 1358854496 14.227 99 16 32 0 1376184224 13048448 4281280
21:52:12 82304 8192000000 10263894960 10731663360 1337390800 14.034 98 11 22 0 1359511792 19825568 2295424
21:54:40 44523 8192000000 10260781008 10724028416 1358230592 14.221 99 11 22 0 1369486592 7949760 3306240
21:56:25 28864 8192000000 10254067728 10698203136 1318030096 13.859 96 27 54 0 1332002688 7637888 6334704
21:57:57 73528 8192000000 10276168928 10730913792 1343970800 14.093 98 17 34 0 1362310624 14273728 4066096
22:00:21 67153 8192000000 10282087968 10752946176 1375351200 14.375 100 17 34 0 1393843136 14693824 3798112
22:02:28 51445 8192000000 10294528224 10741669888 1366678688 14.297 99 18 36 0 1380150928 9776336 3695904
22:04:20 12392 8192000000 10304842256 10744807424 1376594608 14.386 99 48 96 0 1397822528 10907584 10320336
22:05:34 86685 8192000000 10297588576 10770423808 1365570736 14.287 98 11 22 0 1381421456 13830352 2020368
22:08:01 113623 8192000000 10313824944 10765869056 1399146416 14.587 100 16 31 3 1414480336 11487264 3846656
22:11:09 33523 8192000000 10295999728 10766225408 1359026560 14.229 97 21 40 2 1385038848 21668944 4343344
22:12:50 55973 8192000000 10286930224 10748928000 1383213744 14.445 99 12 24 0 1391582800 5919392 2449664
22:14:59 127082 8192000000 10286868400 10762903552 1347636960 14.126 96 9 18 2 1361665280 12180528 1847792
22:18:10 27958 8192000000 10314709264 10771992576 1386146176 14.471 98 28 56 0 1400965056 8511648 6307232
22:19:48 33716 8192000000 10301718784 10757378048 1378275920 14.401 98 25 50 1 1399238432 14681456 6281056
22:21:38 52772 8192000000 10329623872 10769035264 1389056320 14.497 99 52 104 0 1421668816 20544592 12067904
22:23:43 128038 8192000000 10287165088 10742628352 1282458544 13.535 92 13 26 0 1304960000 19742096 2759360
22:26:57 33214 8192000000 10305362608 10749857792 1366508480 14.296 99 37 74 2 1393166640 18548528 8109632
22:28:38 139330 8192000000 10267968992 10723221504 1359592816 14.234 100 9 18 0 1378759712 17223200 1943696
22:32:04 43714 8192000000 10235327968 10689900544 1323894880 13.912 98 7 14 0 1332342544 6790928 1656736
22:33:50 71552 8192000000 10228786144 10688086016 1304214608 13.734 97 9 18 0 1315266448 8850672 2201168
22:36:05 69533 8192000000 10214881088 10665283584 1290215744 13.606 96 18 36 0 1301518288 7043280 4259264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-05-2022 22:40:19 to 10-05-2022 22:40:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-05-2022 22:40:35 to 10-05-2022 22:40:37.

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

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

10.05.22 22:40:38. 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-05-10 22:40:41 No severe PSQL-related problems occured
10.05.22 22:40:42. End of report.