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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-31 16:38:29
DTS_END                         2022-07-31 16:38:29.7740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35792
TRN_ID                          250615
ATT_ID                          698
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: 9371.53 16163 1124584
customer order: creation 180.68 358 21682
customer order: refuse 14.89 387 1787
order to supplier: creation 47.22 1221 5666
order to supplier: removal 3.59 90689 431
invoice (draft): creation 47.63 3273 5715
invoice (draft): removal 52.91 746 6349
invoice accept: apply 43.52 17032 5222
invoice accept: cancel 47.47 785 5696
customer reserve: creation 3941.82 171 473018
customer reserve: removal 4686.43 54 562371
realization accept: apply 87.89 202 10547
realization accept: cancel 84.37 104 10124
payment from customer: creation 62.01 115 7441
payment from customer: removal 5.60 72 672
payment to supplier: creation 49.83 153 5979
payment to supplier: removal 3.17 68 380
service: total inventory turnovers 4.85 983 582
service: total monetary turnovers 6.80 27 816
service: aggregate perf. data 0.85 18967 102
service: refresh index statistics 0.03 187860 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-07-2022 16:40:22 to 31-07-2022 16:40:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3412
TEST_TIME 2 2514
TEST_TIME 3 4093
TEST_TIME 4 4053
TEST_TIME 5 4385
TEST_TIME 6 4805
TEST_TIME 7 5241
TEST_TIME 8 5161
TEST_TIME 9 5161
TEST_TIME 10 5207
TEST_TIME 11 5924
TEST_TIME 12 5922
TEST_TIME 13 6022
TEST_TIME 14 6547
TEST_TIME 15 6562
TEST_TIME 16 6828
TEST_TIME 17 6957
TEST_TIME 18 7484
TEST_TIME 19 7664
TEST_TIME 20 8148
TEST_TIME 21 8426
TEST_TIME 22 8843
TEST_TIME 23 8834
TEST_TIME 24 8836
TEST_TIME 25 9055
TEST_TIME 26 8961
TEST_TIME 27 9409
TEST_TIME 28 10256
TEST_TIME 29 10143
TEST_TIME 30 10391
TEST_TIME 31 10375
TEST_TIME 32 10249
TEST_TIME 33 10035
TEST_TIME 34 10027
TEST_TIME 35 9854
TEST_TIME 36 10213
TEST_TIME 37 10190
TEST_TIME 38 10164
TEST_TIME 39 10023
TEST_TIME 40 9953
TEST_TIME 41 9953
TEST_TIME 42 9900
TEST_TIME 43 9993
TEST_TIME 44 10039
TEST_TIME 45 9903
TEST_TIME 46 9769
TEST_TIME 47 9645
TEST_TIME 48 9749
TEST_TIME 49 9771
TEST_TIME 50 9826
TEST_TIME 51 9687
TEST_TIME 52 9648
TEST_TIME 53 9543
TEST_TIME 54 9630
TEST_TIME 55 9566
TEST_TIME 56 9501
TEST_TIME 57 9625
TEST_TIME 58 9658
TEST_TIME 59 9651
TEST_TIME 60 9607
TEST_TIME 61 9759
TEST_TIME 62 9784
TEST_TIME 63 9841
TEST_TIME 64 9812
TEST_TIME 65 9899
TEST_TIME 66 9861
TEST_TIME 67 9885
TEST_TIME 68 9933
TEST_TIME 69 9945
TEST_TIME 70 9895
TEST_TIME 71 9803
TEST_TIME 72 9813
TEST_TIME 73 9742
TEST_TIME 74 9751
TEST_TIME 75 9722
TEST_TIME 76 9670
TEST_TIME 77 9623
TEST_TIME 78 9601
TEST_TIME 79 9564
TEST_TIME 80 9566
TEST_TIME 81 9551
TEST_TIME 82 9548
TEST_TIME 83 9547
TEST_TIME 84 9556
TEST_TIME 85 9595
TEST_TIME 86 9585
TEST_TIME 87 9579
TEST_TIME 88 9598
TEST_TIME 89 9578
TEST_TIME 90 9675
TEST_TIME 91 9687
TEST_TIME 92 9698
TEST_TIME 93 9672
TEST_TIME 94 9699
TEST_TIME 95 9649
TEST_TIME 96 9617
TEST_TIME 97 9574
TEST_TIME 98 9555
TEST_TIME 99 9521
TEST_TIME 100 9488
TEST_TIME 101 9477
TEST_TIME 102 9456
TEST_TIME 103 9482
TEST_TIME 104 9460
TEST_TIME 105 9546
TEST_TIME 106 9585
TEST_TIME 107 9565
TEST_TIME 108 9545
TEST_TIME 109 9641
TEST_TIME 110 9590
TEST_TIME 111 9682
TEST_TIME 112 9700
TEST_TIME 113 9660
TEST_TIME 114 9595
TEST_TIME 115 9551
TEST_TIME 116 9555
TEST_TIME 117 9512
TEST_TIME 118 9463
TEST_TIME 119 9419
TEST_TIME 120 9373

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-07-2022 16:40:25 to 31-07-2022 16: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 1564592 1564592 0 0.00 0 35335 6 0 0
doc_list_biud 1564592 1564592 0 0.00 0 79681 25 0 0
sp_add_invoice_to_stock 5352 5222 130 2.42 16 173055 17032 0 130
sp_cancel_adding_invoice 5696 5696 0 0.00 4 81410 785 0 0
sp_cancel_client_order 1787 1787 0 0.00 4 25201 387 0 0
sp_cancel_customer_reserve 562371 562371 0 0.00 0 46722 55 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 3726 72 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 5160 69 0 0
sp_cancel_supplier_invoice 6362 6349 13 0.20 2 79689 747 0 13
sp_cancel_supplier_order 431 431 0 0.00 7 1106848 90689 0 0
sp_cancel_write_off 10124 10124 0 0.00 1 16165 104 0 0
sp_client_order 21682 21682 0 0.00 3 45244 358 0 0
sp_customer_reserve 473018 473018 0 0.00 0 48326 171 0 0
sp_fill_shopping_cart 33057 33057 0 0.00 1 27190 37 0 0
sp_fill_shopping_cart_clo_res 473015 473015 0 0.00 0 11390 12 0 0
sp_get_clo_for_invoice 5218 5218 0 0.00 0 26337 286 0 0
sp_kill_cost_storno 16872 16872 0 0.00 0 9560 48 0 0
sp_kill_qstorno_ret_qs2qd 1141646 1141646 0 0.00 0 79675 30 0 0
sp_kill_qty_storno 602522 602522 0 0.00 0 79679 64 0 0
sp_lock_selected_doc 21642 21642 0 0.00 0 8075 6 0 0
sp_make_cost_storno 29184 29184 0 0.00 0 35331 51 0 0
sp_multiply_rows_for_pdistr 29184 29184 0 0.00 0 8843 26 0 0
sp_multiply_rows_for_qdistr 21682 21682 0 0.00 0 45237 165 0 0
sp_pay_from_customer 7441 7441 0 0.00 4 22179 116 0 0
sp_pay_to_supplier 5979 5979 0 0.00 4 51258 153 0 0
sp_payment_common 13420 13420 0 0.00 1 51227 35 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 0 11995 304 0 0
sp_qd_handle_on_invoice_upd_sts 10913 10913 0 0.00 0 40101 321 0 0
sp_qd_handle_on_reserve_upd_sts 10547 10547 0 0.00 0 21018 61 0 0
sp_reserve_write_off 10547 10547 0 0.00 6 27119 203 0 0
sp_supplier_invoice 5715 5715 0 0.00 6 99156 3274 0 0
sp_supplier_order 5666 5666 0 0.00 5 47050 1221 0 0
srv_aggregate_perf_data 102 102 0 0.00 2593 166670 18968 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1475 128065 44536 0 0
srv_find_qd_qs_mism 1076324 1076324 0 0.00 0 15985 5 0 0
srv_make_invnt_saldo 582 582 0 0.00 42 43381 983 0 0
srv_make_money_saldo 816 816 0 0.00 1 3820 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 139545 220480 187861 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 5222 5222 0 0.00 0 12817 174 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 10947 123 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 1926 12 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 947 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 1356 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 1394 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 7706 30 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 23749 1642 0 0
t$perf-norm:sp_cancel_write_off 1063 1063 0 0.00 0 2552 8 0 0
t$perf-norm:sp_client_order 21682 21682 0 0.00 0 10597 16 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 2530 8 0 0
t$perf-norm:sp_pay_from_customer 7441 7441 0 0.00 0 7953 7 0 0
t$perf-norm:sp_pay_to_supplier 5979 5979 0 0.00 0 6118 10 0 0
t$perf-norm:sp_reserve_write_off 10547 10547 0 0.00 0 8692 12 0 0
t$perf-norm:sp_supplier_invoice 5715 5715 0 0.00 0 11331 20 0 0
t$perf-norm:sp_supplier_order 5666 5666 0 0.00 0 7475 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 808 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 96 2 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 1435 24 0 0
t$perf-norm:srv_make_money_saldo 816 816 0 0.00 0 10303 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 1 0 0
v_add_invoice_to_stock 10684 10424 260 2.43 0 24354 27 0 260
v_all_customers 39858 39858 0 0.00 0 22 1 0 0
v_all_suppliers 31268 31268 0 0.00 0 153 1 0 0
v_all_wares 43364 43364 0 0.00 0 85 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2079 10 0 0
v_cancel_client_order 3574 3574 0 0.00 0 24643 33 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 3714 9 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 2415 6 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 135 3 0 0
v_cancel_supplier_invoice 872 846 26 2.98 0 7658 27 0 26
v_cancel_supplier_order 862 862 0 0.00 0 4490 11 0 0
v_cancel_write_off 2126 2126 0 0.00 0 13622 30 0 0
v_max_id_clo_ord 5664 5664 0 0.00 0 3010 2 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 2337 16 0 0
v_max_id_ord_sup 5710 5710 0 0.00 0 5794 3 0 0
v_max_non_paid_invoice 1719 1719 0 0.00 0 626 3 0 0
v_max_non_paid_realizn 3909 3909 0 0.00 0 1503 3 0 0
v_min_id_clo_ord 5664 5664 0 0.00 0 4771 8 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 7344 14 0 0
v_min_id_ord_sup 5710 5710 0 0.00 0 6493 10 0 0
v_min_non_paid_invoice 5979 5979 0 0.00 0 51224 32 0 0
v_min_non_paid_realizn 7441 7441 0 0.00 0 7684 23 0 0
v_our_firm 10570 10570 0 0.00 0 21 1 0 0
v_reserve_write_off 21094 21094 0 0.00 0 26446 18 0 0
x_lock_depdocs_on_canc_invoice 11622 11622 0 0.00 0 38168 137 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 19879 469 0 0
x_make_qstorno_1000_1200 5666 5666 0 0.00 1 47041 1139 0 0
x_make_qstorno_1200_2000 5715 5715 0 0.00 3 99011 3140 0 0
x_make_qstorno_2100_3300 473018 473018 0 0.00 0 48321 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-07-2022 16:40:30 to 31-07-2022 16: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
14:08:33 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:34 27136 8192000000 9890959328 10198425600 973631456 10.622 100 92 184 0 1004401040 6214912 24554672
14:11:04 14801 8192000000 9924505728 10241159168 1009046896 10.966 100 88 176 0 1039802336 7519152 23236288
14:12:40 13051 8192000000 9966216224 10290667520 1049030816 11.351 100 83 166 0 1079087184 8986848 21069520
14:13:59 8398 8192000000 10030736592 10374684672 1117662208 12.005 100 86 173 0 1148566064 10575696 20328160
14:15:11 5274 8192000000 10090017280 10447437824 1178398608 12.575 100 89 178 0 1210823840 12471200 19954032
14:16:18 2811 8192000000 10131555280 10498011136 1217783344 12.941 100 86 168 2 1249794208 13508064 18502800
14:17:23 3021 8192000000 10160572112 10537451520 1245692032 13.199 100 82 164 0 1277773264 14053520 18027712
14:18:28 1311 8192000000 10192459840 10579243008 1285319360 13.562 100 79 158 0 1315429536 12715376 17394800
14:19:31 9930 8192000000 10177091824 10595123200 1300923232 13.704 100 53 104 0 1322849776 10287888 11638656
14:20:45 10116 8192000000 10207064064 10608672768 1308033472 13.768 100 31 62 0 1320007504 4901408 7072624
14:21:56 10008 8192000000 10197444304 10615390208 1316356832 13.844 100 18 36 0 1326037232 5920880 3759520
14:23:06 10325 8192000000 10215275280 10626723840 1324634000 13.919 100 18 37 1 1334205840 5996128 3575712
14:24:19 6986 8192000000 10218769776 10631163904 1336168080 14.023 100 41 82 0 1352719104 6887024 9664000
14:25:30 26017 8192000000 10223687552 10640543744 1330333968 13.970 100 22 44 0 1340651040 5257312 5059760
14:26:56 8166 8192000000 10222614928 10646695936 1336481888 14.026 100 19 38 0 1346542480 5766720 4293872
14:28:07 12045 8192000000 10173378560 10579234816 1226569904 13.022 93 12 24 0 1233341808 4452464 2319440
14:29:19 52934 8192000000 10165746912 10563379200 1212545216 12.893 94 35 68 0 1226666064 4933584 9187264
14:31:12 3590 8192000000 10123317488 10512715776 1219166896 12.954 98 43 83 1 1235795040 7170256 9457888
14:32:16 7914 8192000000 10150950272 10548097024 1235843184 13.108 98 28 53 1 1248315728 5315120 7157424
14:33:24 16497 8192000000 10185931920 10589265920 1286269360 13.570 100 23 46 0 1297114272 5058272 5786640
14:34:41 27922 8192000000 10183864096 10602774528 1300511200 13.700 100 23 44 1 1310448224 5061088 4875936
14:36:10 5526 8192000000 10204903056 10618494976 1310202912 13.788 100 16 33 0 1319782752 5390832 4189008
14:37:16 8415 8192000000 10204250464 10621071360 1315873456 13.839 100 26 52 0 1328005376 6212080 5919840
14:38:24 32509 8192000000 10239236224 10649341952 1333144672 13.996 100 32 63 3 1344545328 4174048 7226608
14:40:01 70332 8192000000 10249174032 10671656960 1348478720 14.134 100 39 78 0 1368807488 11819872 8508896
14:42:25 29415 8192000000 10270636064 10688991232 1337300832 14.033 98 48 93 0 1361912096 13879312 10731952
14:44:02 19258 8192000000 10288122080 10711293952 1378198000 14.400 100 47 94 0 1406974224 17602240 11173984
14:45:27 20521 8192000000 10280710064 10707767296 1354133376 14.185 98 23 46 0 1374849648 14863696 5852576
14:46:56 3733 8192000000 10302077776 10714812416 1370389696 14.331 99 59 117 1 1402511792 18226592 13895504
14:48:02 4096 8192000000 10306560960 10722631680 1381346448 14.429 100 62 124 1 1419014608 22494144 15174016
14:49:17 4437 8192000000 10314186832 10728431616 1387510800 14.484 100 58 113 1 1423661904 21709040 14442064
14:50:37 4500 8192000000 10319088000 10735161344 1387700544 14.485 100 55 109 0 1421907408 21627888 12578976
14:51:47 3785 8192000000 10311604672 10721816576 1376377632 14.384 100 69 135 0 1417105712 24075968 16652112
14:52:57 1984 8192000000 10301855424 10712961024 1370340976 14.330 100 77 150 2 1411169424 21859776 18968672
14:54:03 12180 8192000000 10282254560 10694041600 1342088608 14.076 99 52 102 0 1380241344 24936512 13216224
14:55:19 1473 8192000000 10319878160 10719326208 1368434512 14.313 100 90 179 0 1420519008 29877456 22207040
14:56:25 5088 8192000000 10315995264 10730364928 1370682208 14.333 100 64 126 0 1418216160 31734240 15799712
14:57:37 8176 8192000000 10295099584 10715136000 1349483888 14.143 99 48 97 0 1394317248 32367168 12466192
14:58:56 4583 8192000000 10302306608 10714783744 1355256432 14.195 100 51 100 1 1405409152 36924976 13227744
15:00:02 9556 8192000000 10311029808 10735079424 1366816608 14.299 100 38 74 0 1417671872 40606544 10248720
15:01:17 14194 8192000000 10308285248 10734624768 1370399888 14.331 100 50 97 1 1416754752 34099776 12255088
15:02:40 14851 8192000000 10308183168 10742272000 1380929952 14.425 100 23 46 0 1419125824 32185520 6010352
15:03:58 16581 8192000000 10315450480 10756988928 1363319616 14.267 99 19 38 0 1414617472 45975728 5322128
15:05:16 20718 8192000000 10324768832 10762350592 1376342544 14.384 100 23 46 1 1429827680 46845264 6639872
15:06:40 46319 8192000000 10286109264 10731749376 1373982704 14.363 100 14 28 0 1393333296 15342688 4007904
15:08:29 69662 8192000000 10279756320 10716725248 1328331472 13.952 96 13 27 0 1338794960 7821328 2642160
15:10:47 79549 8192000000 10317345728 10745401344 1389187616 14.499 100 41 81 1 1415427248 16043328 10196304
15:13:23 88622 8192000000 10311212208 10758152192 1393443456 14.537 100 38 76 0 1424853744 21965232 9445056
15:15:54 23545 8192000000 10307611168 10755465216 1401773504 14.611 100 33 70 0 1417156352 8102032 7280816
15:17:21 46133 8192000000 10292834960 10744029184 1374445408 14.367 98 18 36 0 1385163424 6423264 4294752
15:19:42 21265 8192000000 10315039264 10767200256 1384377024 14.456 98 21 42 0 1402108592 12585296 5146272
15:21:04 57363 8192000000 10309899568 10779533312 1411299376 14.695 100 24 48 1 1434640560 16941616 6399568
15:23:02 41165 8192000000 10309906896 10767835136 1403502240 14.626 100 13 27 0 1420162624 13916912 2743472
15:24:46 75890 8192000000 10322762384 10786947072 1392053200 14.524 98 25 48 0 1421595936 23197968 6344768
15:27:02 49227 8192000000 10324334320 10771525632 1405142624 14.641 100 28 57 2 1435164880 23247136 6775120
15:29:00 71938 8192000000 10287372608 10750644224 1381727072 14.432 98 24 48 1 1395439264 8240784 5471408
15:31:17 85023 8192000000 10298226048 10774253568 1401550240 14.609 100 12 25 0 1419969472 15455712 2963520
15:33:43 30968 8192000000 10273523808 10723799040 1329689728 13.964 96 27 54 1 1344419136 8026480 6702928
15:35:23 61384 8192000000 10283783632 10746556416 1366920288 14.299 99 15 30 0 1385412656 15210544 3281824
15:37:31 18445 8192000000 10292308752 10739625984 1357466032 14.215 98 33 67 0 1377870016 13190288 7213696
15:38:52 60351 8192000000 10272877952 10720714752 1335523040 14.017 97 26 48 0 1355649312 14755520 5370752
15:40:53 69532 8192000000 10269463936 10725085184 1330652656 13.973 97 11 22 1 1347570944 14851056 2067232
15:43:14 47530 8192000000 10244714784 10691538944 1301770304 13.711 96 17 34 0 1317315744 11553424 3992016
15:45:01 43583 8192000000 10241157376 10678018048 1289881472 13.603 96 11 22 0 1302910128 10576000 2452656
15:46:46 92162 8192000000 10274670272 10722529280 1346584720 14.117 99 21 42 0 1364847424 14018512 4244192
15:49:19 102657 8192000000 10265932560 10725097472 1351664272 14.162 99 29 58 1 1373673024 15448512 6560240
15:52:10 48102 8192000000 10257247232 10714652672 1343589952 14.090 98 35 70 1 1361047680 10040384 7417344
15:54:06 42395 8192000000 10267849024 10724941824 1365642496 14.288 99 14 29 0 1377330032 8121360 3566176
15:55:54 80007 8192000000 10273194624 10735730688 1372896480 14.353 100 10 20 1 1390787136 15239984 2650672
15:58:22 40458 8192000000 10272196864 10729033728 1368020240 14.309 99 21 43 0 1379739680 6632176 5087264
16:00:14 42905 8192000000 10277160336 10730303488 1358488080 14.224 99 15 30 0 1369343808 7650848 3204880
16:02:02 56111 8192000000 10299924624 10759868416 1391128496 14.516 100 22 44 1 1412027856 15882800 5016560
16:04:03 54096 8192000000 10306642752 10760978432 1393110064 14.534 100 19 38 0 1415476304 18030352 4335888
16:06:41 89513 8192000000 10314412288 10766585856 1370778688 14.334 98 18 36 0 1393981760 19016608 4186464
16:09:11 24335 8192000000 10305651728 10746064896 1360923792 14.246 97 31 62 0 1375713904 8616832 6173280
16:10:37 94180 8192000000 10304733472 10768900096 1357286240 14.213 97 38 76 0 1390915648 25075472 8553936
16:13:26 112412 8192000000 10285036992 10754035712 1376722992 14.387 99 10 20 0 1391303328 11938976 2641360
16:16:22 50783 8192000000 10272764032 10739281920 1380279232 14.419 100 5 10 0 1389326656 8400256 647168
16:18:14 69466 8192000000 10279946176 10735714304 1318894528 13.867 95 10 20 0 1332500624 11528768 2077328
16:20:24 57788 8192000000 10298072000 10758959104 1371529312 14.341 98 16 32 0 1394090800 18944016 3617472
16:22:27 57565 8192000000 10278561104 10736320512 1330394784 13.971 96 21 43 0 1348809056 13297648 5116624
16:24:29 57560 8192000000 10291248016 10724540416 1331210240 13.978 97 24 44 0 1347603792 11301600 5091952
16:26:29 60316 8192000000 10285694480 10745835520 1333484672 13.999 97 9 18 0 1349731376 13857728 2388976
16:28:56 46537 8192000000 10251219328 10712629248 1334010320 14.003 99 26 52 0 1360439776 20291680 6137776
16:30:46 128065 8192000000 10234661760 10677288960 1268830512 13.411 94 17 32 0 1281398480 8465600 4102368
16:34:08 3699 8192000000 10266268912 10705305600 1350431776 14.151 100 55 112 0 1371148832 7954816 12762240
16:35:12 34567 8192000000 10246940208 10709721088 1354100304 14.184 100 9 18 1 1365530752 8930672 2499776
16:36:47 59614 8192000000 10251982800 10706046976 1312651360 13.810 96 12 24 0 1324853744 9281120 2921264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-07-2022 16:40:35 to 31-07-2022 16:40:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 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 31-07-2022 16:40:51 to 31-07-2022 16: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          248816
MON$OLDEST_ACTIVE               251668
MON$OLDEST_SNAPSHOT             251668
MON$NEXT_TRANSACTION            251668
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                       2047328
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:566: 
:567: mscs-test-161.budzdorov.ru    Sun Jul 31 16:38:30 2022
:568:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:569: 
:570: 
:571: mscs-test-161.budzdorov.ru    Sun Jul 31 16:38:34 2022
:572:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:573: 
:574: 
:575: mscs-test-161.budzdorov.ru    Sun Jul 31 16:38:39 2022
:576:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:577: 
--- end of diff output ---

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

31.07.22 16: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-07-31 16:40:57 No severe PSQL-related problems occured
31.07.22 16:40:58. End of report.