Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.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 15732992 kB
MemAvailable 30891176 kB
Buffers 0 kB
Cached 15954536 kB
SwapCached 2520 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-08-12 22:38:25
DTS_END                         2022-08-12 22:38:25.7150
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/38490
TRN_ID                          248914
ATT_ID                          716
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: 9716.39 22453 1165967
customer order: creation 177.00 372 21240
customer order: refuse 15.23 387 1827
order to supplier: creation 46.20 1310 5544
order to supplier: removal 3.73 96487 447
invoice (draft): creation 47.35 3402 5682
invoice (draft): removal 54.66 779 6559
invoice accept: apply 43.84 17777 5261
invoice accept: cancel 49.42 767 5930
customer reserve: creation 4123.71 171 494845
customer reserve: removal 4858.04 58 582965
realization accept: apply 85.75 209 10290
realization accept: cancel 76.28 114 9154
payment from customer: creation 61.95 128 7434
payment from customer: removal 6.11 82 733
payment to supplier: creation 50.73 124 6088
payment to supplier: removal 3.58 87 430
service: total inventory turnovers 4.98 1125 598
service: total monetary turnovers 6.90 28 828
service: aggregate perf. data 0.90 18610 108
service: refresh index statistics 0.03 307040 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-08-2022 22:39:34 to 12-08-2022 22:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2061
TEST_TIME 2 2292
TEST_TIME 3 3950
TEST_TIME 4 4483
TEST_TIME 5 4341
TEST_TIME 6 4767
TEST_TIME 7 4605
TEST_TIME 8 4737
TEST_TIME 9 4628
TEST_TIME 10 4905
TEST_TIME 11 5642
TEST_TIME 12 6277
TEST_TIME 13 6678
TEST_TIME 14 6902
TEST_TIME 15 7358
TEST_TIME 16 7594
TEST_TIME 17 7667
TEST_TIME 18 7645
TEST_TIME 19 8171
TEST_TIME 20 8025
TEST_TIME 21 8215
TEST_TIME 22 8416
TEST_TIME 23 8978
TEST_TIME 24 9559
TEST_TIME 25 9394
TEST_TIME 26 10404
TEST_TIME 27 10801
TEST_TIME 28 11499
TEST_TIME 29 11470
TEST_TIME 30 11328
TEST_TIME 31 11280
TEST_TIME 32 11160
TEST_TIME 33 10924
TEST_TIME 34 10869
TEST_TIME 35 10863
TEST_TIME 36 10797
TEST_TIME 37 10660
TEST_TIME 38 10677
TEST_TIME 39 10513
TEST_TIME 40 10469
TEST_TIME 41 10486
TEST_TIME 42 10569
TEST_TIME 43 10409
TEST_TIME 44 10325
TEST_TIME 45 10407
TEST_TIME 46 10317
TEST_TIME 47 10163
TEST_TIME 48 10087
TEST_TIME 49 10218
TEST_TIME 50 10113
TEST_TIME 51 10171
TEST_TIME 52 10209
TEST_TIME 53 10187
TEST_TIME 54 10201
TEST_TIME 55 10175
TEST_TIME 56 10113
TEST_TIME 57 10106
TEST_TIME 58 10142
TEST_TIME 59 10334
TEST_TIME 60 10363
TEST_TIME 61 10453
TEST_TIME 62 10452
TEST_TIME 63 10374
TEST_TIME 64 10245
TEST_TIME 65 10210
TEST_TIME 66 10304
TEST_TIME 67 10205
TEST_TIME 68 10135
TEST_TIME 69 10068
TEST_TIME 70 9990
TEST_TIME 71 9946
TEST_TIME 72 9865
TEST_TIME 73 9920
TEST_TIME 74 9853
TEST_TIME 75 9873
TEST_TIME 76 9930
TEST_TIME 77 9896
TEST_TIME 78 9886
TEST_TIME 79 9898
TEST_TIME 80 9829
TEST_TIME 81 9878
TEST_TIME 82 9844
TEST_TIME 83 10023
TEST_TIME 84 10051
TEST_TIME 85 10066
TEST_TIME 86 10136
TEST_TIME 87 10109
TEST_TIME 88 10081
TEST_TIME 89 9987
TEST_TIME 90 9989
TEST_TIME 91 9958
TEST_TIME 92 9952
TEST_TIME 93 9941
TEST_TIME 94 9915
TEST_TIME 95 9856
TEST_TIME 96 9854
TEST_TIME 97 9852
TEST_TIME 98 9830
TEST_TIME 99 9846
TEST_TIME 100 9869
TEST_TIME 101 9860
TEST_TIME 102 9843
TEST_TIME 103 9858
TEST_TIME 104 9874
TEST_TIME 105 9911
TEST_TIME 106 9970
TEST_TIME 107 9979
TEST_TIME 108 9977
TEST_TIME 109 9978
TEST_TIME 110 9898
TEST_TIME 111 9878
TEST_TIME 112 9824
TEST_TIME 113 9867
TEST_TIME 114 9818
TEST_TIME 115 9761
TEST_TIME 116 9734
TEST_TIME 117 9724
TEST_TIME 118 9763
TEST_TIME 119 9727
TEST_TIME 120 9717

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-08-2022 22:39:38 to 12-08-2022 22:39:41.

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 1622450 1622450 0 0.00 0 25256 6 0 0
doc_list_biud 1622447 1622447 0 0.00 0 27486 27 0 0
sp_add_invoice_to_stock 5411 5261 150 2.77 39 166168 17778 0 150
sp_cancel_adding_invoice 5930 5930 0 0.00 4 66136 767 0 0
sp_cancel_client_order 1827 1827 0 0.00 4 24756 388 0 0
sp_cancel_customer_reserve 582965 582965 0 0.00 0 27488 58 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 3 7571 83 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 4858 87 0 0
sp_cancel_supplier_invoice 6576 6559 17 0.25 1 23179 780 0 17
sp_cancel_supplier_order 447 447 0 0.00 11 1163017 96487 0 0
sp_cancel_write_off 9154 9154 0 0.00 1 10065 115 0 0
sp_client_order 21240 21240 0 0.00 3 28112 373 0 0
sp_customer_reserve 494845 494845 0 0.00 0 82451 172 0 0
sp_fill_shopping_cart 32464 32464 0 0.00 1 21816 40 0 0
sp_fill_shopping_cart_clo_res 494844 494844 0 0.00 0 18109 15 0 0
sp_get_clo_for_invoice 5259 5259 0 0.00 0 30097 343 0 0
sp_kill_cost_storno 16247 16247 0 0.00 0 20008 53 0 0
sp_kill_qstorno_ret_qs2qd 1182090 1182090 0 0.00 0 25067 31 0 0
sp_kill_qty_storno 622430 622430 0 0.00 0 27485 69 0 0
sp_lock_selected_doc 21553 21553 0 0.00 0 7916 7 0 0
sp_make_cost_storno 29071 29071 0 0.00 0 23205 69 0 0
sp_multiply_rows_for_pdistr 29069 29069 0 0.00 0 8316 29 0 0
sp_multiply_rows_for_qdistr 21240 21240 0 0.00 0 25051 164 0 0
sp_pay_from_customer 7434 7434 0 0.00 4 23958 129 0 0
sp_pay_to_supplier 6088 6088 0 0.00 4 25036 124 0 0
sp_payment_common 13520 13520 0 0.00 1 12099 29 0 0
sp_qd_handle_on_cancel_clo 1827 1827 0 0.00 0 24748 354 0 0
sp_qd_handle_on_invoice_upd_sts 11189 11189 0 0.00 0 14758 339 0 0
sp_qd_handle_on_reserve_upd_sts 10289 10289 0 0.00 0 17367 64 0 0
sp_reserve_write_off 10290 10290 0 0.00 6 25299 210 0 0
sp_supplier_invoice 5682 5682 0 0.00 6 84881 3403 0 0
sp_supplier_order 5544 5544 0 0.00 5 39798 1310 0 0
srv_aggregate_perf_data 108 108 0 0.00 2619 200765 18611 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1449 106167 42113 0 0
srv_find_qd_qs_mism 1115098 1115098 0 0.00 0 17571 5 0 0
srv_make_invnt_saldo 598 598 0 0.00 29 39118 1125 0 0
srv_make_money_saldo 828 828 0 0.00 1 1172 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 242068 402976 307040 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5261 5261 0 0.00 0 13576 209 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 12847 108 0 0
t$perf-norm:sp_cancel_client_order 1827 1827 0 0.00 0 5525 14 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 4007 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 3097 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 518 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 1214 21 0 0
t$perf-norm:sp_cancel_supplier_order 447 447 0 0.00 0 41642 1665 0 0
t$perf-norm:sp_cancel_write_off 1070 1070 0 0.00 0 10022 19 0 0
t$perf-norm:sp_client_order 21240 21240 0 0.00 0 11557 21 0 0
t$perf-norm:sp_customer_reserve 1088 1088 0 0.00 0 3792 8 0 0
t$perf-norm:sp_pay_from_customer 7434 7434 0 0.00 0 18775 21 0 0
t$perf-norm:sp_pay_to_supplier 6088 6088 0 0.00 0 7544 14 0 0
t$perf-norm:sp_reserve_write_off 10290 10290 0 0.00 0 6076 13 0 0
t$perf-norm:sp_supplier_invoice 5682 5682 0 0.00 0 5993 22 0 0
t$perf-norm:sp_supplier_order 5544 5544 0 0.00 0 6561 18 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1642 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 491 9 0 0
t$perf-norm:srv_make_invnt_saldo 598 598 0 0.00 0 1873 21 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 1052 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10816 10516 300 2.77 0 12350 19 0 300
v_all_customers 38768 38768 0 0.00 0 20 1 0 0
v_all_suppliers 31264 31264 0 0.00 0 128 1 0 0
v_all_wares 42480 42480 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 401 6 0 0
v_cancel_client_order 3654 3654 0 0.00 0 12357 9 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 5308 8 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 5033 9 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 4351 12 0 0
v_cancel_supplier_invoice 880 846 34 3.86 0 1060 18 0 34
v_cancel_supplier_order 894 894 0 0.00 0 1326 7 0 0
v_cancel_write_off 2140 2140 0 0.00 0 5045 15 0 0
v_max_id_clo_ord 5544 5544 0 0.00 0 185 1 0 0
v_max_id_clo_res 1088 1088 0 0.00 0 1165 8 0 0
v_max_id_ord_sup 5680 5680 0 0.00 0 5366 4 0 0
v_max_non_paid_invoice 1679 1679 0 0.00 0 236 3 0 0
v_max_non_paid_realizn 3981 3981 0 0.00 0 3028 5 0 0
v_min_id_clo_ord 5544 5544 0 0.00 0 6374 9 0 0
v_min_id_clo_res 1088 1088 0 0.00 0 10545 16 0 0
v_min_id_ord_sup 5680 5680 0 0.00 0 6520 11 0 0
v_min_non_paid_invoice 6087 6087 0 0.00 0 8037 19 0 0
v_min_non_paid_realizn 7433 7433 0 0.00 0 12093 23 0 0
v_our_firm 10616 10616 0 0.00 0 16 1 0 0
v_reserve_write_off 20578 20578 0 0.00 0 7027 6 0 0
x_lock_depdocs_on_canc_invoice 12066 12066 0 0.00 0 20899 130 0 0
x_lock_depdocs_on_canc_sup_ord 447 447 0 0.00 0 11490 358 0 0
x_make_qstorno_1000_1200 5544 5544 0 0.00 1 39775 1234 0 0
x_make_qstorno_1200_2000 5682 5682 0 0.00 3 79449 3254 0 0
x_make_qstorno_2100_3300 494844 494844 0 0.00 0 82435 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2022 22:39:42 to 12-08-2022 22:39:46.

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:29 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:30 7074 8192000000 9905775712 10213580800 1000224928 10.881 100 88 177 0 1028254432 5578336 22451168
20:10:57 16492 8192000000 9956848944 10277945344 1030800592 11.176 100 90 180 0 1062115472 8635344 22679536
20:12:22 12550 8192000000 9967220304 10299285504 1055850192 11.417 100 77 154 0 1085199632 9983872 19365568
20:13:37 10904 8192000000 10027815456 10371465216 1104779712 11.883 100 81 161 0 1140027456 16005504 19242240
20:14:52 4638 8192000000 10073204080 10431979520 1158131120 12.386 100 80 156 1 1187909856 11009600 18769136
20:16:03 5938 8192000000 10121895040 10492104704 1213800496 12.904 100 75 150 0 1244059168 14022320 16236352
20:17:11 2138 8192000000 10166572352 10539618304 1253363664 13.269 100 84 167 0 1283972096 12745280 17863152
20:18:15 859 8192000000 10195160832 10575659008 1282587184 13.537 100 85 171 0 1315792832 14239472 18966176
20:19:17 879 8192000000 10215093264 10603716608 1303468832 13.727 100 90 181 0 1337258240 12985248 20804160
20:20:19 7029 8192000000 10230088720 10619285504 1310030464 13.786 100 84 169 0 1341580960 12659552 18890944
20:21:27 2947 8192000000 10199203120 10611421184 1314508512 13.827 100 31 64 0 1328651104 6748016 7394576
20:22:33 9651 8192000000 10201764976 10620452864 1321313648 13.889 100 18 36 0 1331242368 5830928 4097792
20:23:43 14194 8192000000 10204612688 10627166208 1326156608 13.932 100 19 39 0 1336292608 5805776 4330224
20:24:58 2099 8192000000 10220717408 10636763136 1332244400 13.987 100 38 76 1 1346647008 6106672 8295936
20:26:03 18696 8192000000 10244828016 10651471872 1332728672 13.992 100 55 106 0 1351255840 6266224 12260944
20:27:23 4407 8192000000 10235543568 10657374208 1327735088 13.947 99 38 76 1 1342787424 6064112 8988224
20:28:34 8613 8192000000 10204961744 10622889984 1315806192 13.839 100 26 52 1 1327356336 6065216 5484928
20:29:44 19105 8192000000 10152139040 10556641280 1215170288 12.917 94 29 58 0 1231497824 9723776 6603760
20:31:06 3920 8192000000 10155426304 10556760064 1208297568 12.853 94 35 70 0 1221066848 4544608 8224672
20:32:16 10637 8192000000 10152734960 10552774656 1231438032 13.067 97 11 22 0 1238703824 5005040 2260752
20:33:33 76086 8192000000 10170233104 10578092032 1260891360 13.338 98 31 62 2 1277362048 9292400 7178288
20:35:50 6907 8192000000 10189175168 10605289472 1302310240 13.716 100 20 40 0 1313270976 6302064 4658672
20:37:00 10594 8192000000 10204421904 10616430592 1314970320 13.831 100 19 40 0 1324067504 4788352 4308832
20:38:13 11712 8192000000 10212379856 10634686464 1328347968 13.952 100 18 36 0 1337596560 5117280 4131312
20:39:26 86001 8192000000 10246801536 10667225088 1344711392 14.100 100 36 69 0 1360776016 8446880 7617744
20:41:57 5985 8192000000 10284905408 10700165120 1368081696 14.310 100 60 119 0 1398105952 15946896 14077360
20:43:07 4493 8192000000 10285157200 10703933440 1372968464 14.354 100 48 93 1 1398260928 14878704 10413760
20:44:15 12508 8192000000 10293770944 10714697728 1382881968 14.442 100 45 90 2 1411330624 17178704 11269952
20:45:35 17558 8192000000 10305305216 10725453824 1382981296 14.443 99 58 116 1 1415946848 18277008 14688544
20:47:06 9199 8192000000 10309704560 10734882816 1380532640 14.421 98 46 92 2 1410890864 18672560 11685664
20:48:19 3786 8192000000 10342237360 10764812288 1409182432 14.677 100 62 122 0 1452067808 27578704 15306672
20:49:29 3714 8192000000 10351012208 10775601152 1412103488 14.703 100 62 121 0 1456444864 28696432 15644944
20:50:36 2497 8192000000 10343243584 10757132288 1398731776 14.584 100 84 165 0 1447669408 29064832 19872800
20:51:42 2375 8192000000 10332563568 10746945536 1381529968 14.430 99 71 139 1 1431160832 32482240 17148624
20:52:50 5003 8192000000 10346653392 10761302016 1394801248 14.549 100 68 136 0 1450519984 39294032 16424704
20:53:59 2357 8192000000 10356800112 10767335424 1398497904 14.582 100 88 170 1 1456377552 37281744 20597904
20:55:06 4126 8192000000 10351937472 10772324352 1402361232 14.616 100 59 116 0 1459181632 42770784 14049616
20:56:19 4354 8192000000 10361262864 10770452480 1395786672 14.557 100 82 164 0 1465017792 49416304 19814816
20:57:27 6664 8192000000 10320902800 10743275520 1376198960 14.383 100 43 82 2 1431064640 44118880 10746800
20:58:42 2379 8192000000 10363290496 10766544896 1386013808 14.470 100 79 156 0 1459855920 54105216 19736896
20:59:51 7853 8192000000 10365971520 10784468992 1392719216 14.530 100 53 101 2 1466874160 60576976 13577968
21:01:05 1447 8192000000 10385447712 10791473152 1395251776 14.553 100 89 177 0 1482786016 64270320 23263920
21:02:09 19735 8192000000 10352116272 10784284672 1387468608 14.483 100 35 70 0 1455767968 57633360 10666000
21:03:50 26815 8192000000 10331542928 10783703040 1381771232 14.432 100 33 66 0 1450219744 58512480 9936032
21:06:15 40288 8192000000 10264640464 10734637056 1327996704 13.949 97 7 14 0 1351745552 21957360 1791488
21:07:56 106167 8192000000 10283729104 10723336192 1333539632 13.999 98 16 32 0 1351480240 14875072 3065536
21:10:46 103740 8192000000 10277188816 10723852288 1348367984 14.133 98 31 62 0 1362518400 6936256 7214160
21:13:47 94387 8192000000 10291885280 10741989376 1355665168 14.198 98 18 36 1 1367489792 8578416 3246208
21:16:21 62544 8192000000 10295827904 10743267328 1383988592 14.452 100 18 36 0 1398004992 9372512 4643888
21:18:31 34485 8192000000 10315475808 10764406784 1400024176 14.595 100 22 44 0 1418391472 13322240 5045056
21:20:50 38695 8192000000 10294910784 10751664128 1370602336 14.332 98 12 24 1 1386847200 13542640 2702224
21:22:29 42098 8192000000 10298517760 10759598080 1392989488 14.533 100 15 30 1 1410517936 14160208 3368240
21:24:16 64242 8192000000 10296572064 10753380352 1358987472 14.228 97 24 47 0 1374212400 9710912 5514016
21:26:28 63686 8192000000 10297437008 10759675904 1376321296 14.384 99 14 27 0 1395604960 16183520 3100144
21:28:42 49021 8192000000 10282088976 10737213440 1351183952 14.158 98 21 42 0 1366818304 10281776 5352576
21:30:57 48706 8192000000 10298481200 10744868864 1356685696 14.208 98 32 64 0 1375072832 11596080 6791056
21:32:48 87780 8192000000 10289140944 10746064896 1347127696 14.122 97 21 42 0 1361859984 10232352 4499936
21:35:46 58101 8192000000 10306037616 10770960384 1371341392 14.339 98 24 48 0 1397430096 19956976 6131728
21:37:44 45057 8192000000 10295243344 10751733760 1373489872 14.358 99 10 18 0 1384979648 9550576 1939200
21:40:08 63908 8192000000 10308641856 10764668928 1359889696 14.236 97 7 14 0 1371677232 10468832 1318704
21:42:13 88425 8192000000 10289577728 10739609600 1347011648 14.121 97 36 72 0 1370933696 15274064 8647984
21:44:47 51964 8192000000 10262783200 10730631168 1359935072 14.237 99 17 34 0 1373188608 9492864 3760672
21:46:39 88096 8192000000 10267050784 10715242496 1307592224 13.764 95 26 51 0 1320982928 7830528 5560176
21:49:12 39795 8192000000 10265565072 10720514048 1354031680 14.184 99 12 23 0 1367158784 10343296 2783808
21:51:18 72858 8192000000 10277767104 10732236800 1358579536 14.225 99 15 30 0 1376071568 14327408 3164624
21:53:32 60073 8192000000 10269829344 10737397760 1364893856 14.281 100 15 30 0 1381579552 12933744 3751952
21:55:33 20152 8192000000 10272387472 10717724672 1342169776 14.077 98 25 50 0 1357101312 9284208 5647328
21:56:55 46197 8192000000 10276135264 10724139008 1356906912 14.210 99 37 74 0 1384345440 19588880 7849648
21:58:42 14160 8192000000 10281748672 10718466048 1347693840 14.127 99 48 94 0 1375100000 16682416 10723744
22:00:00 42093 8192000000 10291045104 10740666368 1354708560 14.190 99 25 50 0 1384737488 24525552 5503376
22:01:49 89073 8192000000 10270089152 10730270720 1317606320 13.855 96 9 18 0 1332028752 12638112 1784320
22:04:23 43565 8192000000 10288246368 10748833792 1374288496 14.365 100 13 26 0 1392439264 15442432 2708336
22:06:07 98675 8192000000 10288092128 10737659904 1379984208 14.416 100 33 66 1 1396322512 8901584 7436720
22:08:51 57972 8192000000 10291220896 10748809216 1386247808 14.472 100 20 40 0 1401331808 10627408 4456592
22:10:49 16908 8192000000 10301588720 10755932160 1396164224 14.561 100 29 56 1 1409015024 7216736 5634064
22:12:08 53298 8192000000 10280274240 10750115840 1391900288 14.523 100 12 24 0 1400931280 6555328 2475664
22:14:01 50072 8192000000 10292891600 10755317760 1393319728 14.535 100 7 14 0 1401792496 7164352 1308416
22:16:03 23427 8192000000 10280083744 10732048384 1362309776 14.258 98 26 51 0 1374201040 6700304 5190960
22:17:27 59033 8192000000 10274056192 10733490176 1344992304 14.102 97 18 37 0 1359192832 9992384 4208144
22:19:28 34538 8192000000 10286486448 10740572160 1378011632 14.399 100 21 42 0 1391190432 8621248 4557552
22:21:39 30249 8192000000 10280797584 10734403584 1340486848 14.062 97 31 62 0 1357946416 10844912 6614656
22:23:11 63089 8192000000 10300740528 10765824000 1373537216 14.359 99 29 58 1 1396649920 16734704 6378000
22:25:29 46926 8192000000 10297758384 10753335296 1385662064 14.467 100 31 62 0 1404384720 11274128 7448528
22:27:16 60657 8192000000 10230718080 10676703232 1251231152 13.250 91 22 44 1 1263650864 7737536 4682176
22:29:48 45017 8192000000 10262961760 10711797760 1333028288 13.995 98 29 56 0 1349819328 10067824 6723216
22:31:40 65672 8192000000 10244274208 10718633984 1342424416 14.079 99 9 18 0 1356065488 11485232 2155840
22:33:48 40164 8192000000 10236003904 10691981312 1317130336 13.851 97 20 40 1 1327588048 5879216 4578496
22:35:31 40935 8192000000 10239447376 10691764224 1325154672 13.923 99 14 28 0 1340869824 12266672 3448480
22:37:12 62370 8192000000 10257650336 10712551424 1315431136 13.835 97 20 41 0 1332306112 12235728 4639248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-08-2022 22:39:48 to 12-08-2022 22:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2022 22:40:04 to 12-08-2022 22:40:06.

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          246870
MON$OLDEST_ACTIVE               249171
MON$OLDEST_SNAPSHOT             249171
MON$NEXT_TRANSACTION            249171
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                       2047136
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:156: 
:157: mscs-test-161.budzdorov.ru    Fri Aug 12 22:38:29 2022
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:159: 
:160: 
:161: mscs-test-161.budzdorov.ru    Fri Aug 12 22:38:31 2022
:162:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:163: 
:164: 
:165: mscs-test-161.budzdorov.ru    Fri Aug 12 22:38:31 2022
:166:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:167: 
:168: 
:169: mscs-test-161.budzdorov.ru    Fri Aug 12 22:38:32 2022
:170:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:171: 
--- end of diff output ---

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

12.08.22 22:40:07. 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-08-12 22:40:10 No severe PSQL-related problems occured
12.08.22 22:40:11. End of report.