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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-05 22:38:16
DTS_END                         2022-04-05 22:38:16.4060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60016
TRN_ID                          243352
ATT_ID                          661
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: 9345.43 18173 1121452
customer order: creation 175.96 360 21115
customer order: refuse 14.75 389 1770
order to supplier: creation 45.91 1302 5509
order to supplier: removal 3.41 96089 409
invoice (draft): creation 46.89 3684 5627
invoice (draft): removal 52.69 737 6323
invoice accept: apply 42.48 17576 5097
invoice accept: cancel 47.54 724 5705
customer reserve: creation 3957.84 173 474941
customer reserve: removal 4655.06 55 558607
realization accept: apply 85.49 179 10259
realization accept: cancel 84.37 107 10124
payment from customer: creation 61.48 141 7377
payment from customer: removal 5.93 61 712
payment to supplier: creation 49.87 148 5984
payment to supplier: removal 3.58 63 429
service: total inventory turnovers 4.65 1072 558
service: total monetary turnovers 6.58 28 790
service: aggregate perf. data 0.93 19886 112
service: refresh index statistics 0.03 220683 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-04-2022 22:39:51 to 05-04-2022 22:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4327
TEST_TIME 2 4573
TEST_TIME 3 3716
TEST_TIME 4 3284
TEST_TIME 5 4155
TEST_TIME 6 4652
TEST_TIME 7 4579
TEST_TIME 8 4873
TEST_TIME 9 5008
TEST_TIME 10 5137
TEST_TIME 11 5167
TEST_TIME 12 5645
TEST_TIME 13 6021
TEST_TIME 14 6514
TEST_TIME 15 6719
TEST_TIME 16 6764
TEST_TIME 17 7630
TEST_TIME 18 7719
TEST_TIME 19 8309
TEST_TIME 20 8495
TEST_TIME 21 9185
TEST_TIME 22 9419
TEST_TIME 23 9501
TEST_TIME 24 10037
TEST_TIME 25 10157
TEST_TIME 26 10658
TEST_TIME 27 10835
TEST_TIME 28 10723
TEST_TIME 29 10617
TEST_TIME 30 10351
TEST_TIME 31 10278
TEST_TIME 32 10128
TEST_TIME 33 10326
TEST_TIME 34 10314
TEST_TIME 35 10252
TEST_TIME 36 10194
TEST_TIME 37 10096
TEST_TIME 38 10048
TEST_TIME 39 10094
TEST_TIME 40 10132
TEST_TIME 41 10079
TEST_TIME 42 10004
TEST_TIME 43 9887
TEST_TIME 44 9782
TEST_TIME 45 9849
TEST_TIME 46 9888
TEST_TIME 47 9802
TEST_TIME 48 9899
TEST_TIME 49 9831
TEST_TIME 50 9892
TEST_TIME 51 9941
TEST_TIME 52 9880
TEST_TIME 53 10099
TEST_TIME 54 10043
TEST_TIME 55 10083
TEST_TIME 56 10040
TEST_TIME 57 10025
TEST_TIME 58 10136
TEST_TIME 59 10103
TEST_TIME 60 10059
TEST_TIME 61 9934
TEST_TIME 62 9856
TEST_TIME 63 9794
TEST_TIME 64 9844
TEST_TIME 65 9748
TEST_TIME 66 9688
TEST_TIME 67 9724
TEST_TIME 68 9654
TEST_TIME 69 9614
TEST_TIME 70 9645
TEST_TIME 71 9614
TEST_TIME 72 9670
TEST_TIME 73 9642
TEST_TIME 74 9721
TEST_TIME 75 9712
TEST_TIME 76 9762
TEST_TIME 77 9744
TEST_TIME 78 9733
TEST_TIME 79 9740
TEST_TIME 80 9841
TEST_TIME 81 9923
TEST_TIME 82 9855
TEST_TIME 83 9746
TEST_TIME 84 9736
TEST_TIME 85 9665
TEST_TIME 86 9675
TEST_TIME 87 9660
TEST_TIME 88 9653
TEST_TIME 89 9619
TEST_TIME 90 9530
TEST_TIME 91 9535
TEST_TIME 92 9614
TEST_TIME 93 9599
TEST_TIME 94 9601
TEST_TIME 95 9608
TEST_TIME 96 9613
TEST_TIME 97 9619
TEST_TIME 98 9623
TEST_TIME 99 9611
TEST_TIME 100 9657
TEST_TIME 101 9624
TEST_TIME 102 9652
TEST_TIME 103 9623
TEST_TIME 104 9665
TEST_TIME 105 9740
TEST_TIME 106 9725
TEST_TIME 107 9667
TEST_TIME 108 9595
TEST_TIME 109 9621
TEST_TIME 110 9587
TEST_TIME 111 9553
TEST_TIME 112 9525
TEST_TIME 113 9488
TEST_TIME 114 9513
TEST_TIME 115 9477
TEST_TIME 116 9443
TEST_TIME 117 9430
TEST_TIME 118 9427
TEST_TIME 119 9409
TEST_TIME 120 9345

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-04-2022 22:39:55 to 05-04-2022 22:39:58.

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 1560133 1560133 0 0.00 0 17081 6 0 0
doc_list_biud 1560132 1560132 0 0.00 0 83500 25 0 0
sp_add_invoice_to_stock 5251 5097 154 2.93 48 180244 17577 0 154
sp_cancel_adding_invoice 5705 5705 0 0.00 4 46375 724 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 9915 389 0 0
sp_cancel_customer_reserve 558607 558607 0 0.00 1 83501 55 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 2 2539 62 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 1123 63 0 0
sp_cancel_supplier_invoice 6333 6323 10 0.15 2 12067 738 0 10
sp_cancel_supplier_order 409 409 0 0.00 8 1062917 96089 0 0
sp_cancel_write_off 10124 10124 0 0.00 1 9942 108 0 0
sp_client_order 21115 21115 0 0.00 3 27487 361 0 0
sp_customer_reserve 474941 474941 0 0.00 0 123800 173 0 0
sp_fill_shopping_cart 32236 32236 0 0.00 1 12027 35 0 0
sp_fill_shopping_cart_clo_res 474941 474941 0 0.00 0 22559 14 0 0
sp_get_clo_for_invoice 5097 5097 0 0.00 0 30995 294 0 0
sp_kill_cost_storno 16970 16970 0 0.00 0 6480 53 0 0
sp_kill_qstorno_ret_qs2qd 1134070 1134070 0 0.00 0 83497 30 0 0
sp_kill_qty_storno 598292 598292 0 0.00 0 83499 65 0 0
sp_lock_selected_doc 21212 21212 0 0.00 0 7085 3 0 0
sp_make_cost_storno 28715 28715 0 0.00 0 12306 60 0 0
sp_multiply_rows_for_pdistr 28714 28714 0 0.00 0 8176 29 0 0
sp_multiply_rows_for_qdistr 21115 21115 0 0.00 0 26664 154 0 0
sp_pay_from_customer 7377 7377 0 0.00 4 13559 141 0 0
sp_pay_to_supplier 5984 5984 0 0.00 4 13407 149 0 0
sp_payment_common 13360 13360 0 0.00 1 8842 43 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 9910 363 0 0
sp_qd_handle_on_invoice_upd_sts 10800 10800 0 0.00 0 19012 311 0 0
sp_qd_handle_on_reserve_upd_sts 10259 10259 0 0.00 0 23608 70 0 0
sp_reserve_write_off 10259 10259 0 0.00 6 23632 179 0 0
sp_supplier_invoice 5627 5627 0 0.00 7 84686 3684 0 0
sp_supplier_order 5509 5509 0 0.00 5 64241 1302 0 0
srv_aggregate_perf_data 112 112 0 0.00 3003 410065 19887 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 3373 115923 46706 0 0
srv_find_qd_qs_mism 1071969 1071969 0 0.00 0 17079 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 43 45194 1073 0 0
srv_make_money_saldo 790 790 0 0.00 1 3098 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 166634 272248 220684 0 0
t$perf-abend:sp_add_invoice_to_stock 154 154 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5097 5097 0 0.00 0 14681 161 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 1283 50 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 4424 10 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 6142 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 2564 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 3346 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 414 9 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 20764 1566 0 0
t$perf-norm:sp_cancel_write_off 1050 1050 0 0.00 0 2302 8 0 0
t$perf-norm:sp_client_order 21115 21115 0 0.00 0 10313 16 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 1579 5 0 0
t$perf-norm:sp_pay_from_customer 7378 7378 0 0.00 0 6623 11 0 0
t$perf-norm:sp_pay_to_supplier 5984 5984 0 0.00 0 3634 8 0 0
t$perf-norm:sp_reserve_write_off 10259 10259 0 0.00 0 8560 13 0 0
t$perf-norm:sp_supplier_invoice 5627 5627 0 0.00 0 9380 24 0 0
t$perf-norm:sp_supplier_order 5509 5509 0 0.00 0 9639 27 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 471 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 889 18 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 4198 21 0 0
t$perf-norm:srv_make_money_saldo 790 790 0 0.00 0 548 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 234 60 0 0
v_add_invoice_to_stock 10498 10190 308 2.93 0 7796 20 0 308
v_all_customers 38662 38662 0 0.00 0 19 0 0 0
v_all_suppliers 30884 30884 0 0.00 0 38 1 0 0
v_all_wares 42228 42228 0 0.00 0 34 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1802 7 0 0
v_cancel_client_order 3540 3540 0 0.00 0 4528 8 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 2531 7 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 59 2 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 516 4 0 0
v_cancel_supplier_invoice 780 760 20 2.56 0 1812 15 0 20
v_cancel_supplier_order 818 818 0 0.00 0 327 3 0 0
v_cancel_write_off 2100 2100 0 0.00 0 3274 13 0 0
v_max_id_clo_ord 5508 5508 0 0.00 0 2649 2 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 2033 12 0 0
v_max_id_ord_sup 5613 5613 0 0.00 0 4017 4 0 0
v_max_non_paid_invoice 1662 1662 0 0.00 0 275 3 0 0
v_max_non_paid_realizn 3961 3961 0 0.00 0 1560 5 0 0
v_min_id_clo_ord 5508 5508 0 0.00 0 4681 7 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 2604 7 0 0
v_min_id_ord_sup 5613 5613 0 0.00 0 6108 9 0 0
v_min_non_paid_invoice 5983 5983 0 0.00 0 8235 31 0 0
v_min_non_paid_realizn 7377 7377 0 0.00 0 8835 38 0 0
v_our_firm 10396 10396 0 0.00 0 28 1 0 0
v_reserve_write_off 20516 20516 0 0.00 0 5674 6 0 0
x_lock_depdocs_on_canc_invoice 11648 11648 0 0.00 0 12785 114 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 8426 348 0 0
x_make_qstorno_1000_1200 5509 5509 0 0.00 2 64225 1227 0 0
x_make_qstorno_1200_2000 5627 5627 0 0.00 4 84634 3559 0 0
x_make_qstorno_2100_3300 474941 474941 0 0.00 0 123799 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-04-2022 22:39:59 to 05-04-2022 22:40:03.

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:20 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:21 23892 8192000000 9892725264 10201649152 979430656 10.679 100 87 174 0 1008684480 6246944 23006880
20:10:50 16637 8192000000 9943118976 10265206784 1029463712 11.163 100 84 168 0 1059623424 8170576 21989136
20:12:22 16080 8192000000 9986967872 10319433728 1070216720 11.554 100 86 172 0 1100890880 9232176 21441984
20:13:50 14543 8192000000 10037419552 10386309120 1125621296 12.080 100 72 144 1 1152488736 9697632 17169808
20:15:08 12106 8192000000 10105974160 10466308096 1184690064 12.634 100 74 148 0 1213069360 12359184 16020112
20:16:27 4388 8192000000 10137841536 10509242368 1229647008 13.051 100 75 149 1 1258376560 12238992 16490560
20:17:33 2740 8192000000 10175386240 10552426496 1263410144 13.361 100 81 160 1 1292897264 12016976 17470144
20:18:37 2378 8192000000 10205282848 10590920704 1291615360 13.619 100 88 174 2 1324001200 11987872 20397968
20:19:42 26256 8192000000 10204048256 10605998080 1307105424 13.760 100 42 84 4 1325393680 8018256 10270000
20:21:13 4558 8192000000 10206680016 10612936704 1312297296 13.807 100 40 80 3 1329858400 8638112 8922992
20:22:22 5079 8192000000 10210560000 10624520192 1320214992 13.879 100 37 74 2 1336480000 7668272 8596736
20:23:27 14466 8192000000 10213373088 10630131712 1327677424 13.946 100 23 46 3 1338481424 5302944 5501056
20:24:42 28380 8192000000 10229486576 10644348928 1331519680 13.981 100 37 73 1 1344880464 5220064 8140720
20:26:13 2461 8192000000 10244915824 10662051840 1345921328 14.111 100 45 91 1 1363522496 6136544 11464624
20:27:17 8480 8192000000 10216836720 10635419648 1307386544 13.762 98 16 32 1 1316268112 5159680 3721888
20:28:26 6610 8192000000 10205059680 10618695680 1295669200 13.656 98 19 38 0 1305647472 5429072 4549200
20:29:33 34310 8192000000 10207032112 10601619456 1277272432 13.488 98 44 86 3 1292180768 4593184 10315152
20:31:10 8963 8192000000 10153886592 10556325888 1224255856 13.001 96 19 38 1 1234316032 4913536 5146640
20:32:19 9802 8192000000 10143035520 10538500096 1214828688 12.914 97 25 50 0 1225410144 4930256 5651200
20:33:29 12223 8192000000 10167364544 10574569472 1278649840 13.501 100 21 42 0 1288373696 4824032 4899824
20:34:47 34313 8192000000 10181988368 10589913088 1275862928 13.475 99 34 67 2 1289384176 5877152 7644096
20:36:23 5613 8192000000 10190857840 10602840064 1299137664 13.687 100 43 85 4 1316152800 6105104 10910032
20:37:29 11636 8192000000 10198939792 10617016320 1311959600 13.804 100 19 38 0 1321470576 5000032 4510944
20:38:44 27403 8192000000 10243378880 10655318016 1334985264 14.012 100 41 79 0 1353796112 9718192 9092656
20:40:18 11013 8192000000 10264206656 10682912768 1360098400 14.238 100 37 74 0 1378778896 10578976 8101520
20:41:31 107068 8192000000 10288269744 10714472448 1367126240 14.301 99 44 88 0 1393318080 15630416 10561424
20:44:26 5059 8192000000 10304163584 10720120832 1383116928 14.444 100 63 124 2 1412860496 15763888 13979680
20:45:37 13724 8192000000 10322511248 10746765312 1401690640 14.610 100 52 104 1 1438269744 24205360 12373744
20:47:11 7694 8192000000 10347791984 10763767808 1412348784 14.705 100 74 144 0 1453991808 24506128 17136896
20:48:26 9392 8192000000 10346760992 10768252928 1404651472 14.636 99 58 113 0 1445414624 25940464 14822688
20:49:40 4099 8192000000 10344623664 10760273920 1403002672 14.622 100 71 140 1 1451622464 31580256 17039536
20:50:49 3895 8192000000 10362548016 10779295744 1407338720 14.660 99 77 150 1 1464660016 38877680 18443616
20:51:58 5657 8192000000 10342129152 10758217728 1395746000 14.557 99 73 145 1 1449371392 35656400 17968992
20:53:09 4260 8192000000 10356041952 10770997248 1404420416 14.634 100 61 122 0 1459051952 39072016 15559520
20:54:20 3721 8192000000 10348314384 10758742016 1391793648 14.522 100 74 148 0 1454409424 44035552 18580224
20:55:30 3371 8192000000 10376544400 10786508800 1405484560 14.644 100 80 160 0 1476245088 50275152 20485376
20:56:36 20269 8192000000 10321211152 10744328192 1351628800 14.162 98 43 85 0 1404135552 40954352 11552400
20:58:00 22619 8192000000 10331505952 10756898816 1381520896 14.430 100 35 70 0 1433307296 42438432 9347968
20:59:24 9881 8192000000 10337838176 10768519168 1383335552 14.446 100 43 86 2 1445374368 50600768 11438048
21:00:36 21159 8192000000 10300267920 10743410688 1353745248 14.181 99 22 44 2 1397497760 37655168 6097344
21:02:03 14168 8192000000 10303532672 10735996928 1353437424 14.178 99 28 56 2 1402144128 41230816 7475888
21:03:21 41477 8192000000 10275868128 10713366528 1335946048 14.021 99 16 33 1 1373810800 33403456 4461296
21:05:03 41531 8192000000 10256606896 10686562304 1339018240 14.049 99 16 33 1 1351165232 8073952 4073040
21:06:47 13028 8192000000 10268756736 10698035200 1342596272 14.081 99 36 70 1 1359087664 9293920 7197472
21:08:43 34613 8192000000 10273645008 10710093824 1345801344 14.110 98 37 74 0 1367638032 14465264 7371424
21:10:19 87248 8192000000 10308942272 10739781632 1385539920 14.466 100 37 73 1 1413363168 19540272 8282976
21:12:48 79645 8192000000 10305865680 10751512576 1385826016 14.469 100 20 40 0 1399616000 9687616 4102368
21:15:08 48145 8192000000 10324133472 10766135296 1403591728 14.627 100 45 90 0 1427189664 12806672 10791264
21:17:13 34736 8192000000 10287410896 10750205952 1383867840 14.451 100 18 35 1 1405617584 16584496 5165248
21:18:50 25949 8192000000 10294459376 10752077824 1396653168 14.565 100 24 48 0 1410309264 8333472 5322624
21:20:26 55131 8192000000 10307142016 10773258240 1393218816 14.535 99 22 46 2 1411441904 13086016 5137072
21:22:22 18234 8192000000 10311247840 10768146432 1406711760 14.655 100 21 42 0 1428906992 16722672 5472560
21:23:48 64564 8192000000 10293153536 10764570624 1386621456 14.476 99 12 24 0 1408994352 19082320 3290576
21:26:00 60134 8192000000 10306312880 10770358272 1357924128 14.219 97 16 32 2 1382101296 20503280 3673888
21:28:07 60386 8192000000 10316680624 10764214272 1379017856 14.408 99 24 48 0 1397550976 12640368 5892752
21:30:22 77759 8192000000 10301215280 10774700032 1382054864 14.435 99 15 31 0 1413737104 28072160 3610080
21:32:40 26870 8192000000 10294487216 10743328768 1365174544 14.284 98 39 78 1 1383046096 9616480 8255072
21:34:17 31825 8192000000 10283744320 10724061184 1348373648 14.133 98 25 50 0 1361826864 7937984 5515232
21:35:53 71549 8192000000 10274774352 10723704832 1329248560 13.960 97 20 40 1 1344480640 11009024 4223056
21:38:06 101151 8192000000 10256099216 10695340032 1315617696 13.837 96 33 66 4 1335370080 12225456 7526928
21:41:19 38965 8192000000 10260813968 10712244224 1364165648 14.275 100 23 46 0 1378827392 9294992 5366752
21:43:03 79345 8192000000 10265016928 10723336192 1369475424 14.322 100 12 24 0 1381591808 9138352 2978032
21:45:31 70020 8192000000 10251980032 10707755008 1289214608 13.597 94 11 22 0 1302892528 11371696 2306224
21:47:46 84190 8192000000 10272560272 10730508288 1346775776 14.118 98 19 38 1 1361332576 10443920 4112880
21:50:12 52157 8192000000 10258827072 10703405056 1307742272 13.766 96 20 40 0 1328178992 15679056 4757664
21:52:25 49695 8192000000 10264970064 10710028288 1331313664 13.979 98 9 18 1 1352079856 18293504 2472688
21:54:29 47651 8192000000 10250921952 10688598016 1304635088 13.737 96 24 48 0 1326069984 16004080 5430816
21:56:19 9961 8192000000 10283098272 10715693056 1361296656 14.249 100 42 84 1 1381551344 11178768 9075920
21:57:30 67930 8192000000 10291656064 10749673472 1360709744 14.244 99 21 42 1 1387714912 22359520 4645648
21:59:49 115923 8192000000 10282756560 10722402304 1348797808 14.137 98 27 53 0 1364989040 9692624 6498608
22:02:50 61315 8192000000 10301206224 10744782848 1369220064 14.320 99 14 28 0 1383071248 10604992 3246192
22:04:51 37858 8192000000 10272782880 10737967104 1364624736 14.279 99 12 22 1 1378615168 11241712 2748720
22:06:39 62469 8192000000 10287948192 10755911680 1373194464 14.356 99 8 16 1 1387051136 12131280 1725392
22:08:54 85246 8192000000 10296484880 10772369408 1400577936 14.600 100 15 30 0 1418994080 14854848 3561296
22:11:19 56370 8192000000 10270072000 10729308160 1342285760 14.078 97 13 25 1 1352135872 6926000 2924112
22:13:17 10524 8192000000 10295709056 10743992320 1381598032 14.431 100 51 98 5 1398666976 5498816 11570128
22:14:36 86845 8192000000 10278376832 10733846528 1327366608 13.943 96 21 42 2 1343715696 11835856 4513232
22:17:03 25757 8192000000 10294853536 10738302976 1381304064 14.428 100 31 62 1 1398083248 9493744 7285440
22:18:30 72724 8192000000 10290327856 10741633024 1359021168 14.229 98 18 36 1 1372840288 10088896 3730224
22:20:45 57700 8192000000 10295688448 10745503744 1334775392 14.010 96 13 26 0 1353751952 16292256 2684304
22:23:17 91956 8192000000 10246387824 10699960320 1258721776 13.318 92 10 20 0 1275621824 14931152 1968896
22:25:53 107558 8192000000 10263104816 10722738176 1331401584 13.980 97 17 34 0 1346638256 11534992 3701680
22:28:52 80236 8192000000 10234346720 10692775936 1313116992 13.814 96 15 30 0 1326375616 9770672 3487952
22:31:13 67861 8192000000 10260772064 10718187520 1358355888 14.223 100 18 37 0 1372017088 9472368 4188832
22:33:23 97783 8192000000 10279261600 10732040192 1370931920 14.335 100 16 32 1 1387268144 11944016 4392208
22:36:03 55878 8192000000 10264710272 10726047744 1340671216 14.063 98 15 30 0 1358064960 13885424 3508320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 154 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-04-2022 22:40:21 to 05-04-2022 22:40:23.

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          240394
MON$OLDEST_ACTIVE               243625
MON$OLDEST_SNAPSHOT             243625
MON$NEXT_TRANSACTION            243625
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                       2040992
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.33575 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33575 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33575 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33575 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Apr  5 22:38:27 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Tue Apr  5 22:38:27 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
--- end of diff output ---

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

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