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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16700964 kB
MemAvailable 31654600 kB
Buffers 0 kB
Cached 15042320 kB
SwapCached 2144 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-03-28 01:38:08
DTS_END                         2022-03-28 01:38:08.3630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50210
TRN_ID                          251974
ATT_ID                          655
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: 9522.58 21698 1142709
customer order: creation 185.71 376 22285
customer order: refuse 16.02 339 1923
order to supplier: creation 48.93 1414 5871
order to supplier: removal 3.48 93016 417
invoice (draft): creation 49.46 3347 5935
invoice (draft): removal 51.90 770 6228
invoice accept: apply 45.01 17199 5401
invoice accept: cancel 47.02 874 5642
customer reserve: creation 4134.98 170 496198
customer reserve: removal 4637.05 55 556446
realization accept: apply 89.64 216 10757
realization accept: cancel 76.19 103 9143
payment from customer: creation 63.53 138 7624
payment from customer: removal 6.17 105 740
payment to supplier: creation 51.04 188 6125
payment to supplier: removal 3.49 125 419
service: total inventory turnovers 5.24 887 629
service: total monetary turnovers 6.88 22 825
service: aggregate perf. data 0.81 21301 97
service: refresh index statistics 0.03 293321 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-03-2022 01:39:42 to 28-03-2022 01:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3436
TEST_TIME 2 5589
TEST_TIME 3 4666
TEST_TIME 4 4851
TEST_TIME 5 5699
TEST_TIME 6 5392
TEST_TIME 7 5591
TEST_TIME 8 5619
TEST_TIME 9 6190
TEST_TIME 10 6594
TEST_TIME 11 7288
TEST_TIME 12 7429
TEST_TIME 13 7582
TEST_TIME 14 7672
TEST_TIME 15 7521
TEST_TIME 16 7627
TEST_TIME 17 7894
TEST_TIME 18 8263
TEST_TIME 19 8168
TEST_TIME 20 8224
TEST_TIME 21 8634
TEST_TIME 22 9117
TEST_TIME 23 9211
TEST_TIME 24 9380
TEST_TIME 25 9456
TEST_TIME 26 9539
TEST_TIME 27 10316
TEST_TIME 28 10401
TEST_TIME 29 10439
TEST_TIME 30 10272
TEST_TIME 31 10230
TEST_TIME 32 10148
TEST_TIME 33 10396
TEST_TIME 34 10575
TEST_TIME 35 10506
TEST_TIME 36 10488
TEST_TIME 37 10521
TEST_TIME 38 10316
TEST_TIME 39 10120
TEST_TIME 40 10118
TEST_TIME 41 10138
TEST_TIME 42 9958
TEST_TIME 43 9950
TEST_TIME 44 9836
TEST_TIME 45 9788
TEST_TIME 46 10005
TEST_TIME 47 9940
TEST_TIME 48 9834
TEST_TIME 49 9842
TEST_TIME 50 9754
TEST_TIME 51 9852
TEST_TIME 52 9963
TEST_TIME 53 9885
TEST_TIME 54 9942
TEST_TIME 55 9977
TEST_TIME 56 9936
TEST_TIME 57 9977
TEST_TIME 58 9964
TEST_TIME 59 9999
TEST_TIME 60 10052
TEST_TIME 61 10120
TEST_TIME 62 10067
TEST_TIME 63 10178
TEST_TIME 64 10169
TEST_TIME 65 10040
TEST_TIME 66 10015
TEST_TIME 67 9925
TEST_TIME 68 9887
TEST_TIME 69 9902
TEST_TIME 70 9861
TEST_TIME 71 9802
TEST_TIME 72 9719
TEST_TIME 73 9744
TEST_TIME 74 9719
TEST_TIME 75 9672
TEST_TIME 76 9664
TEST_TIME 77 9619
TEST_TIME 78 9601
TEST_TIME 79 9666
TEST_TIME 80 9677
TEST_TIME 81 9690
TEST_TIME 82 9720
TEST_TIME 83 9710
TEST_TIME 84 9739
TEST_TIME 85 9707
TEST_TIME 86 9760
TEST_TIME 87 9791
TEST_TIME 88 9796
TEST_TIME 89 9786
TEST_TIME 90 9766
TEST_TIME 91 9737
TEST_TIME 92 9679
TEST_TIME 93 9689
TEST_TIME 94 9665
TEST_TIME 95 9613
TEST_TIME 96 9627
TEST_TIME 97 9640
TEST_TIME 98 9612
TEST_TIME 99 9646
TEST_TIME 100 9616
TEST_TIME 101 9676
TEST_TIME 102 9656
TEST_TIME 103 9661
TEST_TIME 104 9650
TEST_TIME 105 9642
TEST_TIME 106 9817
TEST_TIME 107 9775
TEST_TIME 108 9753
TEST_TIME 109 9822
TEST_TIME 110 9739
TEST_TIME 111 9697
TEST_TIME 112 9698
TEST_TIME 113 9692
TEST_TIME 114 9661
TEST_TIME 115 9615
TEST_TIME 116 9570
TEST_TIME 117 9616
TEST_TIME 118 9562
TEST_TIME 119 9587
TEST_TIME 120 9526

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-03-2022 01:39:45 to 28-03-2022 01:39:49.

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 1602179 1602179 0 0.00 0 24184 6 0 0
doc_list_biud 1602177 1602177 0 0.00 0 26472 25 0 0
sp_add_invoice_to_stock 5563 5401 162 2.91 37 210493 17199 0 162
sp_cancel_adding_invoice 5642 5642 0 0.00 5 72257 874 0 0
sp_cancel_client_order 1923 1923 0 0.00 4 17487 339 0 0
sp_cancel_customer_reserve 556446 556446 0 0.00 1 23025 55 0 0
sp_cancel_pay_from_customer 740 740 0 0.00 3 18814 105 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 10322 126 0 0
sp_cancel_supplier_invoice 6233 6228 5 0.08 2 13807 771 0 5
sp_cancel_supplier_order 417 417 0 0.00 9 764018 93017 0 0
sp_cancel_write_off 9143 9143 0 0.00 1 9181 104 0 0
sp_client_order 22285 22285 0 0.00 3 21742 377 0 0
sp_customer_reserve 496198 496198 0 0.00 0 35065 171 0 0
sp_fill_shopping_cart 34081 34081 0 0.00 1 19015 41 0 0
sp_fill_shopping_cart_clo_res 496196 496196 0 0.00 0 14837 13 0 0
sp_get_clo_for_invoice 5397 5397 0 0.00 0 32014 311 0 0
sp_kill_cost_storno 15944 15944 0 0.00 0 10306 52 0 0
sp_kill_qstorno_ret_qs2qd 1128680 1128680 0 0.00 0 23019 30 0 0
sp_kill_qty_storno 595952 595952 0 0.00 0 26469 66 0 0
sp_lock_selected_doc 22308 22308 0 0.00 0 8619 7 0 0
sp_make_cost_storno 29901 29901 0 0.00 0 21142 71 0 0
sp_multiply_rows_for_pdistr 29900 29900 0 0.00 0 10449 32 0 0
sp_multiply_rows_for_qdistr 22284 22284 0 0.00 0 21729 179 0 0
sp_pay_from_customer 7624 7624 0 0.00 4 24870 139 0 0
sp_pay_to_supplier 6125 6125 0 0.00 5 24910 188 0 0
sp_payment_common 13747 13747 0 0.00 1 13115 36 0 0
sp_qd_handle_on_cancel_clo 1923 1923 0 0.00 0 17286 296 0 0
sp_qd_handle_on_invoice_upd_sts 11038 11038 0 0.00 0 26463 335 0 0
sp_qd_handle_on_reserve_upd_sts 10757 10757 0 0.00 0 13049 70 0 0
sp_reserve_write_off 10757 10757 0 0.00 6 24216 217 0 0
sp_supplier_invoice 5935 5935 0 0.00 5 98225 3348 0 0
sp_supplier_order 5871 5871 0 0.00 4 74613 1415 0 0
srv_aggregate_perf_data 97 97 0 0.00 2726 503481 21302 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1468 146704 47888 0 0
srv_find_qd_qs_mism 1091618 1091618 0 0.00 0 18470 5 0 0
srv_make_invnt_saldo 629 629 0 0.00 34 17688 888 0 0
srv_make_money_saldo 825 825 0 0.00 0 742 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 167546 382579 293322 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5401 5401 0 0.00 0 24333 185 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 5123 112 0 0
t$perf-norm:sp_cancel_client_order 1923 1923 0 0.00 0 3253 14 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 804 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 740 740 0 0.00 0 998 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 1402 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 464 10 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 27661 1661 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 5560 13 0 0
t$perf-norm:sp_client_order 22285 22285 0 0.00 0 19109 20 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 2105 5 0 0
t$perf-norm:sp_pay_from_customer 7625 7625 0 0.00 0 19859 14 0 0
t$perf-norm:sp_pay_to_supplier 6125 6125 0 0.00 0 9185 10 0 0
t$perf-norm:sp_reserve_write_off 10757 10757 0 0.00 0 21893 18 0 0
t$perf-norm:sp_supplier_invoice 5935 5935 0 0.00 0 13488 25 0 0
t$perf-norm:sp_supplier_order 5871 5871 0 0.00 0 14080 20 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 80 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 6892 134 0 0
t$perf-norm:srv_make_invnt_saldo 629 629 0 0.00 0 1341 22 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 1657 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 16 6 0 0
v_add_invoice_to_stock 11116 10792 324 2.91 0 18641 20 0 324
v_all_customers 40220 40220 0 0.00 0 20 1 0 0
v_all_suppliers 32334 32334 0 0.00 0 15 1 0 0
v_all_wares 44566 44566 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 882 7 0 0
v_cancel_client_order 3846 3846 0 0.00 0 8114 10 0 0
v_cancel_customer_prepayment 1480 1480 0 0.00 0 18772 17 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 1239 7 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 2540 10 0 0
v_cancel_supplier_invoice 872 862 10 1.14 0 3094 18 0 10
v_cancel_supplier_order 834 834 0 0.00 0 1126 5 0 0
v_cancel_write_off 2102 2102 0 0.00 0 3048 14 0 0
v_max_id_clo_ord 5870 5870 0 0.00 0 7275 3 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 1897 14 0 0
v_max_id_ord_sup 5926 5926 0 0.00 0 6211 5 0 0
v_max_non_paid_invoice 1753 1753 0 0.00 0 714 4 0 0
v_max_non_paid_realizn 4160 4160 0 0.00 0 5160 7 0 0
v_min_id_clo_ord 5870 5870 0 0.00 0 17806 27 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 3647 8 0 0
v_min_id_ord_sup 5926 5926 0 0.00 0 3803 7 0 0
v_min_non_paid_invoice 6124 6124 0 0.00 0 13111 32 0 0
v_min_non_paid_realizn 7623 7623 0 0.00 0 10387 25 0 0
v_our_firm 11272 11272 0 0.00 0 15 1 0 0
v_reserve_write_off 21514 21514 0 0.00 0 18789 9 0 0
x_lock_depdocs_on_canc_invoice 11439 11439 0 0.00 0 10788 115 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 18701 348 0 0
x_make_qstorno_1000_1200 5871 5871 0 0.00 1 74559 1313 0 0
x_make_qstorno_1200_2000 5935 5935 0 0.00 1 90110 3217 0 0
x_make_qstorno_2100_3300 496198 496198 0 0.00 0 35064 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-03-2022 01:39:50 to 28-03-2022 01:39:54.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:11 8 8192000000 8803256416 8816005120 8261248 0.100 3 1 2 0 8402816 67024 74544
23:09:13 35045 8192000000 9938600288 10256441344 1016210528 11.035 100 89 176 1 1045250496 6740144 22299824
23:10:59 18334 8192000000 9980363856 10307899392 1059090048 11.448 100 89 178 2 1091280000 9921376 22268576
23:12:40 16416 8192000000 10004528848 10344783872 1091519472 11.757 100 81 162 0 1122680640 11433984 19727184
23:14:04 8976 8192000000 10047595824 10399191040 1135029456 12.169 100 78 156 2 1165729168 12381248 18318464
23:15:15 7672 8192000000 10083265952 10442403840 1168831472 12.486 100 83 166 1 1201762176 13575248 19355456
23:16:26 3841 8192000000 10125134864 10495217664 1214769488 12.913 100 87 173 1 1247062896 12629664 19663744
23:17:31 4910 8192000000 10169734336 10544758784 1249178784 13.231 100 86 172 1 1282365200 14521696 18664720
23:18:37 1611 8192000000 10208209760 10588901376 1288265872 13.588 100 90 180 0 1321264288 12958400 20040016
23:19:40 40760 8192000000 10213778112 10611421184 1306118112 13.751 100 50 101 1 1323600720 4946160 12536448
23:21:20 7313 8192000000 10204147504 10619604992 1313806816 13.821 100 26 52 1 1327370928 7065520 6498592
23:22:29 8438 8192000000 10214286864 10630737920 1325126160 13.923 100 22 43 3 1336140560 6227888 4786512
23:23:38 8443 8192000000 10226518512 10643218432 1336976192 14.030 100 31 62 2 1350603808 6625728 7001888
23:24:46 61407 8192000000 10226267248 10651258880 1342502176 14.080 100 31 62 0 1355357936 5474704 7381056
23:26:49 6063 8192000000 10231318912 10648498176 1339231296 14.050 100 24 48 0 1351309872 6771536 5307040
23:27:55 10096 8192000000 10212600128 10630930432 1307327680 13.762 99 24 48 0 1323000032 10092112 5580240
23:29:08 14262 8192000000 10180243776 10588479488 1269616592 13.418 98 20 40 0 1280338784 6256784 4465408
23:30:22 21432 8192000000 10148664976 10554830848 1209833744 12.868 95 16 32 0 1219131536 5091552 4206240
23:31:47 6472 8192000000 10125580752 10528247808 1199020240 12.767 96 21 42 0 1209289872 5337024 4932608
23:32:54 6385 8192000000 10150594144 10559045632 1264412480 13.370 100 26 52 2 1277122736 6368144 6342112
23:34:03 11630 8192000000 10162626736 10574749696 1277433424 13.490 100 12 24 1 1285361776 5483312 2445040
23:35:18 32854 8192000000 10181196032 10593812480 1291687840 13.620 100 23 46 0 1302380288 5234192 5458256
23:36:54 7267 8192000000 10192936864 10608791552 1304609552 13.737 100 22 44 2 1315654496 5639296 5405648
23:38:03 13401 8192000000 10219971984 10634596352 1323162800 13.905 100 24 48 0 1333335216 4703200 5469216
23:39:22 14157 8192000000 10255478176 10664275968 1344552032 14.098 100 43 84 3 1364431072 10503184 9375856
23:40:42 88836 8192000000 10272627536 10689429504 1361665488 14.252 100 43 88 3 1381576560 9742944 10168128
23:43:16 3164 8192000000 10296911024 10714836992 1384435360 14.456 100 62 124 2 1412809536 14048144 14326032
23:44:24 13626 8192000000 10304762192 10723614720 1382102144 14.435 99 55 108 1 1407475040 13217248 12155648
23:45:41 2947 8192000000 10321256320 10735173632 1397275488 14.571 100 67 133 1 1428245840 16473456 14496896
23:46:47 4762 8192000000 10315543824 10732490752 1382683392 14.441 99 63 121 3 1414839248 17986704 14169152
23:48:03 11157 8192000000 10310778192 10736754688 1392396816 14.527 100 59 116 0 1428468704 22236832 13835056
23:49:20 6574 8192000000 10298196800 10715090944 1372256064 14.347 99 65 130 1 1407888272 19119936 16512272
23:50:30 1466 8192000000 10320535056 10733481984 1391650576 14.521 100 86 169 3 1430265264 18512032 20102656
23:51:37 5958 8192000000 10307500720 10717671424 1377608928 14.395 100 68 136 1 1414254064 20111424 16533712
23:52:50 4873 8192000000 10300387808 10710372352 1367039488 14.301 99 63 124 2 1403497280 21316912 15140880
23:54:10 3078 8192000000 10327376608 10741198848 1391504624 14.519 100 67 133 1 1439084704 30992048 16588032
23:55:20 2275 8192000000 10320543888 10721136640 1365864832 14.290 99 79 157 0 1424851328 38732096 20254400
23:56:31 3057 8192000000 10332944512 10731233280 1360473920 14.242 99 88 177 0 1421940576 39712096 21754560
23:57:37 7590 8192000000 10326594928 10744295424 1375444400 14.376 99 41 82 1 1427744992 40849424 11451168
23:59:02 10628 8192000000 10351324256 10762756096 1388621904 14.494 100 68 136 0 1457146832 49064960 19459968
00:00:19 16981 8192000000 10334010144 10757582848 1389171904 14.498 99 35 69 2 1435788768 37204480 9412384
00:01:37 21173 8192000000 10321129664 10764206080 1380933344 14.425 99 24 48 0 1428103696 40213344 6957008
00:03:02 29622 8192000000 10308061760 10737397760 1362960688 14.264 99 30 60 1 1404924368 33819280 8144400
00:04:34 43696 8192000000 10294097024 10744107008 1357473808 14.215 100 25 50 2 1401776016 37182992 7119216
00:06:45 43267 8192000000 10293273808 10726604800 1354365632 14.187 99 33 65 1 1379935056 17988992 7580432
00:08:30 67405 8192000000 10292301664 10732179456 1361255280 14.249 99 21 42 2 1380626560 14483120 4888160
00:11:06 31270 8192000000 10274022752 10728235008 1349899984 14.147 99 25 51 1 1381754768 25718912 6135872
00:13:06 84352 8192000000 10298173216 10750119936 1385482640 14.466 100 14 27 1 1398305296 10570880 2251776
00:15:36 39559 8192000000 10320388640 10759061504 1400433936 14.599 100 45 89 1 1424346112 13223504 10688672
00:17:17 84063 8192000000 10303768464 10753691648 1396622912 14.565 100 33 65 2 1413160784 8828320 7709552
00:19:51 80546 8192000000 10301192176 10750140416 1381952896 14.434 98 30 60 0 1399493792 10419216 7121680
00:22:13 40683 8192000000 10316413856 10764464128 1395469392 14.555 99 19 37 2 1409891056 9967984 4453680
00:23:58 23302 8192000000 10312467760 10765762560 1373658336 14.360 98 48 94 2 1401202944 16614960 10929648
00:25:23 63565 8192000000 10314609024 10773372928 1402203872 14.615 100 19 38 0 1425001776 17976880 4821024
00:27:43 68386 8192000000 10320006288 10778677248 1396509952 14.564 99 18 36 0 1417127424 15916704 4700768
00:29:52 100291 8192000000 10320808592 10786279424 1398144032 14.578 99 17 34 1 1419008240 16675104 4189104
00:32:33 36965 8192000000 10300369328 10758873088 1376217856 14.383 98 34 68 1 1398046848 13772160 8056832
00:34:14 36229 8192000000 10285160048 10731851776 1333782528 14.001 96 22 43 0 1348611520 10394960 4434032
00:35:52 45223 8192000000 10268214688 10721714176 1340724320 14.064 98 20 40 0 1358451168 13413760 4313088
00:37:39 117644 8192000000 10269032272 10720825344 1290631200 13.610 94 14 28 1 1310224240 16193088 3399952
00:40:37 82206 8192000000 10307941392 10758791168 1351244704 14.159 97 16 32 0 1367912144 12812720 3854720
00:43:03 117979 8192000000 10269430896 10729316352 1322949184 13.903 96 12 24 0 1336011184 10142176 2919824
00:46:02 40409 8192000000 10283948752 10736504832 1369443280 14.322 99 32 63 4 1384384128 8046288 6894560
00:47:48 92651 8192000000 10256371632 10714378240 1315527056 13.836 95 11 22 0 1326419760 8077008 2815696
00:50:21 72977 8192000000 10267906864 10738499584 1362238496 14.257 99 9 18 0 1377388032 12666336 2483200
00:52:36 59909 8192000000 10278410816 10733867008 1378659648 14.405 100 15 30 1 1390314432 8313856 3340928
00:54:44 62586 8192000000 10276320864 10731155456 1370120672 14.328 99 17 33 0 1381048768 7037232 3890864
00:56:48 46875 8192000000 10280430512 10735267840 1365333152 14.285 99 24 48 1 1382844464 12266208 5245104
00:58:38 35440 8192000000 10284732352 10738106368 1358247472 14.222 98 22 44 0 1371018928 7897232 4874224
01:00:35 54423 8192000000 10283314080 10728779776 1329019440 13.958 96 33 66 0 1346062368 9973984 7068944
01:02:31 45515 8192000000 10281203296 10732126208 1365049920 14.283 99 15 31 1 1375748320 7529648 3168752
01:04:25 108877 8192000000 10287690272 10749042688 1363027664 14.265 98 15 30 0 1378574944 12191536 3355744
01:07:15 107733 8192000000 10288415888 10747969536 1341215808 14.068 96 16 32 0 1358397568 13475232 3706528
01:10:03 50043 8192000000 10277100944 10737328128 1361514592 14.251 99 27 54 1 1381119936 13411632 6193712
01:11:54 74199 8192000000 10260629536 10729582592 1335511488 14.017 97 10 20 0 1345155488 7671968 1972032
01:14:14 64659 8192000000 10274043728 10733076480 1338531424 14.044 97 12 24 0 1348792048 7592032 2668592
01:16:19 28449 8192000000 10297256688 10738827264 1359050384 14.229 98 23 45 2 1372677808 7815616 5811808
01:17:51 60713 8192000000 10294143376 10744578048 1343337040 14.087 97 22 44 0 1356507744 8738784 4431920
01:19:52 61586 8192000000 10297843408 10750754816 1345122928 14.104 97 22 44 0 1357815760 8302544 4390288
01:21:55 44674 8192000000 10291832064 10735431680 1321138160 13.887 96 19 38 0 1342024368 16391984 4494224
01:23:40 146704 8192000000 10300536080 10746601472 1326603312 13.936 96 15 29 0 1353491984 23562096 3326576
01:27:07 79108 8192000000 10285358928 10748645376 1354275632 14.186 98 8 16 0 1369175776 13222192 1677952
01:29:46 45378 8192000000 10261753216 10714804224 1325750624 13.929 96 17 34 1 1342174720 11659504 4764592
01:31:33 55812 8192000000 10262269024 10723221504 1335556080 14.017 97 22 44 1 1355087024 14837904 4693040
01:33:29 68749 8192000000 10266175920 10721140736 1347084688 14.121 98 9 18 0 1361316016 12129296 2102032
01:35:37 73139 8192000000 10263834480 10723065856 1347870976 14.128 98 27 54 2 1362284512 7891328 6522208
01:38:05 12264 8192000000 9136666352 9389445120 304845360 3.587 23 1 2 0 306656928 1769216 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 28-03-2022 01:39:56 to 28-03-2022 01:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 162 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-03-2022 01:40:11 to 28-03-2022 01:40:13.

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          252280
MON$OLDEST_ACTIVE               252281
MON$OLDEST_SNAPSHOT             252281
MON$NEXT_TRANSACTION            252281
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                       2037344
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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'

28.03.22 01:40:14. 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-03-28 01:40:17 No severe PSQL-related problems occured
28.03.22 01:40:18. End of report.