Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.04.2022 16: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 16695652 kB
MemAvailable 31589168 kB
Buffers 0 kB
Cached 15050564 kB
SwapCached 2284 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-07 16:38:16
DTS_END                         2022-04-07 16:38:16.6010
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/58962
TRN_ID                          238882
ATT_ID                          692
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: 9229.68 22600 1107562
customer order: creation 171.49 346 20579
customer order: refuse 14.06 375 1687
order to supplier: creation 45.18 1297 5422
order to supplier: removal 3.33 111078 399
invoice (draft): creation 44.94 3401 5393
invoice (draft): removal 51.56 888 6187
invoice accept: apply 40.88 18261 4906
invoice accept: cancel 46.66 744 5599
customer reserve: creation 3852.78 176 462334
customer reserve: removal 4673.77 60 560853
realization accept: apply 82.28 178 9874
realization accept: cancel 75.61 116 9073
payment from customer: creation 58.48 97 7018
payment from customer: removal 5.34 111 641
payment to supplier: creation 47.39 144 5687
payment to supplier: removal 3.78 72 454
service: total inventory turnovers 4.84 1060 581
service: total monetary turnovers 6.48 31 778
service: aggregate perf. data 0.78 25616 93
service: refresh index statistics 0.03 287954 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-04-2022 16:39:45 to 07-04-2022 16:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4386
TEST_TIME 2 3886
TEST_TIME 3 4449
TEST_TIME 4 3915
TEST_TIME 5 4191
TEST_TIME 6 4898
TEST_TIME 7 5178
TEST_TIME 8 5404
TEST_TIME 9 5759
TEST_TIME 10 5999
TEST_TIME 11 5958
TEST_TIME 12 6194
TEST_TIME 13 6360
TEST_TIME 14 6971
TEST_TIME 15 7007
TEST_TIME 16 7293
TEST_TIME 17 7726
TEST_TIME 18 7524
TEST_TIME 19 7993
TEST_TIME 20 8203
TEST_TIME 21 8041
TEST_TIME 22 8638
TEST_TIME 23 8671
TEST_TIME 24 8599
TEST_TIME 25 8834
TEST_TIME 26 9759
TEST_TIME 27 9750
TEST_TIME 28 10139
TEST_TIME 29 10556
TEST_TIME 30 11012
TEST_TIME 31 10785
TEST_TIME 32 10706
TEST_TIME 33 10528
TEST_TIME 34 10366
TEST_TIME 35 10253
TEST_TIME 36 10162
TEST_TIME 37 9934
TEST_TIME 38 9919
TEST_TIME 39 10014
TEST_TIME 40 9942
TEST_TIME 41 9922
TEST_TIME 42 9992
TEST_TIME 43 9892
TEST_TIME 44 9809
TEST_TIME 45 9667
TEST_TIME 46 9587
TEST_TIME 47 9515
TEST_TIME 48 9475
TEST_TIME 49 9344
TEST_TIME 50 9352
TEST_TIME 51 9298
TEST_TIME 52 9396
TEST_TIME 53 9378
TEST_TIME 54 9377
TEST_TIME 55 9342
TEST_TIME 56 9602
TEST_TIME 57 9566
TEST_TIME 58 9537
TEST_TIME 59 9503
TEST_TIME 60 9733
TEST_TIME 61 9683
TEST_TIME 62 9689
TEST_TIME 63 9616
TEST_TIME 64 9560
TEST_TIME 65 9453
TEST_TIME 66 9519
TEST_TIME 67 9453
TEST_TIME 68 9430
TEST_TIME 69 9416
TEST_TIME 70 9367
TEST_TIME 71 9397
TEST_TIME 72 9349
TEST_TIME 73 9362
TEST_TIME 74 9421
TEST_TIME 75 9421
TEST_TIME 76 9407
TEST_TIME 77 9444
TEST_TIME 78 9438
TEST_TIME 79 9471
TEST_TIME 80 9535
TEST_TIME 81 9520
TEST_TIME 82 9563
TEST_TIME 83 9552
TEST_TIME 84 9487
TEST_TIME 85 9426
TEST_TIME 86 9394
TEST_TIME 87 9388
TEST_TIME 88 9347
TEST_TIME 89 9322
TEST_TIME 90 9291
TEST_TIME 91 9261
TEST_TIME 92 9224
TEST_TIME 93 9243
TEST_TIME 94 9242
TEST_TIME 95 9285
TEST_TIME 96 9293
TEST_TIME 97 9290
TEST_TIME 98 9267
TEST_TIME 99 9396
TEST_TIME 100 9349
TEST_TIME 101 9340
TEST_TIME 102 9439
TEST_TIME 103 9414
TEST_TIME 104 9403
TEST_TIME 105 9525
TEST_TIME 106 9588
TEST_TIME 107 9520
TEST_TIME 108 9456
TEST_TIME 109 9422
TEST_TIME 110 9409
TEST_TIME 111 9394
TEST_TIME 112 9354
TEST_TIME 113 9337
TEST_TIME 114 9345
TEST_TIME 115 9297
TEST_TIME 116 9265
TEST_TIME 117 9293
TEST_TIME 118 9322
TEST_TIME 119 9298
TEST_TIME 120 9230

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-04-2022 16:39:48 to 07-04-2022 16:39:52.

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 1534956 1534956 0 0.00 0 25570 6 0 0
doc_list_biud 1534956 1534956 0 0.00 0 25614 28 0 0
sp_add_invoice_to_stock 5046 4906 140 2.77 21 174602 18261 0 140
sp_cancel_adding_invoice 5599 5599 0 0.00 4 65724 745 0 0
sp_cancel_client_order 1687 1687 0 0.00 4 16638 376 0 0
sp_cancel_customer_reserve 560853 560853 0 0.00 1 25331 61 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 8240 112 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 3 3829 72 0 0
sp_cancel_supplier_invoice 6196 6187 9 0.14 2 21533 889 0 9
sp_cancel_supplier_order 399 399 0 0.00 6 1189647 111078 0 0
sp_cancel_write_off 9073 9073 0 0.00 1 8485 116 0 0
sp_client_order 20579 20579 0 0.00 3 25472 347 0 0
sp_customer_reserve 462334 462334 0 0.00 0 72752 177 0 0
sp_fill_shopping_cart 31390 31390 0 0.00 1 21507 43 0 0
sp_fill_shopping_cart_clo_res 462333 462333 0 0.00 0 13927 15 0 0
sp_get_clo_for_invoice 4905 4905 0 0.00 0 26625 336 0 0
sp_kill_cost_storno 15767 15767 0 0.00 0 8887 61 0 0
sp_kill_qstorno_ret_qs2qd 1137365 1137365 0 0.00 0 25322 33 0 0
sp_kill_qty_storno 598576 598576 0 0.00 0 25614 72 0 0
sp_lock_selected_doc 20363 20363 0 0.00 0 7608 3 0 0
sp_make_cost_storno 27483 27483 0 0.00 0 18677 48 0 0
sp_multiply_rows_for_pdistr 27483 27483 0 0.00 0 14620 29 0 0
sp_multiply_rows_for_qdistr 20579 20579 0 0.00 0 23164 156 0 0
sp_pay_from_customer 7018 7018 0 0.00 4 8999 98 0 0
sp_pay_to_supplier 5687 5687 0 0.00 4 16205 144 0 0
sp_payment_common 12705 12705 0 0.00 1 8994 24 0 0
sp_qd_handle_on_cancel_clo 1687 1687 0 0.00 0 16630 340 0 0
sp_qd_handle_on_invoice_upd_sts 10503 10503 0 0.00 0 25613 336 0 0
sp_qd_handle_on_reserve_upd_sts 9874 9874 0 0.00 0 12192 70 0 0
sp_reserve_write_off 9874 9874 0 0.00 6 12511 178 0 0
sp_supplier_invoice 5393 5393 0 0.00 7 79650 3402 0 0
sp_supplier_order 5422 5422 0 0.00 5 34959 1297 0 0
srv_aggregate_perf_data 93 93 0 0.00 2723 409406 25616 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2872 141896 49336 0 0
srv_find_qd_qs_mism 1059868 1059868 0 0.00 0 25569 6 0 0
srv_make_invnt_saldo 581 581 0 0.00 42 46179 1061 0 0
srv_make_money_saldo 778 778 0 0.00 0 4014 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 257734 319760 287954 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4906 4906 0 0.00 0 18283 199 0 0
t$perf-norm:sp_cancel_adding_invoice 392 392 0 0.00 0 4784 89 0 0
t$perf-norm:sp_cancel_client_order 1687 1687 0 0.00 0 4567 15 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 3787 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 3794 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 227 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 1351 30 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 26033 1831 0 0
t$perf-norm:sp_cancel_write_off 1033 1033 0 0.00 0 3665 15 0 0
t$perf-norm:sp_client_order 20579 20579 0 0.00 0 20289 23 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 1501 8 0 0
t$perf-norm:sp_pay_from_customer 7018 7018 0 0.00 0 3943 9 0 0
t$perf-norm:sp_pay_to_supplier 5687 5687 0 0.00 0 23055 20 0 0
t$perf-norm:sp_reserve_write_off 9874 9874 0 0.00 0 25478 15 0 0
t$perf-norm:sp_supplier_invoice 5393 5393 0 0.00 0 7034 18 0 0
t$perf-norm:sp_supplier_order 5422 5422 0 0.00 0 20546 22 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 238 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 676 15 0 0
t$perf-norm:srv_make_invnt_saldo 581 581 0 0.00 0 17842 54 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 3636 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 106 31 0 0
v_add_invoice_to_stock 10088 9808 280 2.77 0 7819 21 0 280
v_all_customers 37332 37332 0 0.00 0 18 1 0 0
v_all_suppliers 29776 29776 0 0.00 0 52 1 0 0
v_all_wares 41158 41158 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 784 784 0 0.00 0 1449 8 0 0
v_cancel_client_order 3374 3374 0 0.00 0 8095 10 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 8223 17 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 1564 4 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 713 4 0 0
v_cancel_supplier_invoice 718 700 18 2.50 0 699 15 0 18
v_cancel_supplier_order 798 798 0 0.00 0 6605 13 0 0
v_cancel_write_off 2066 2066 0 0.00 0 7106 21 0 0
v_max_id_clo_ord 5420 5420 0 0.00 0 2722 3 0 0
v_max_id_clo_res 949 949 0 0.00 0 2152 16 0 0
v_max_id_ord_sup 5390 5390 0 0.00 0 7399 6 0 0
v_max_non_paid_invoice 1609 1609 0 0.00 0 4418 6 0 0
v_max_non_paid_realizn 3729 3729 0 0.00 0 2952 5 0 0
v_min_id_clo_ord 5420 5420 0 0.00 0 4042 7 0 0
v_min_id_clo_res 949 949 0 0.00 0 1107 3 0 0
v_min_id_ord_sup 5390 5390 0 0.00 0 21346 11 0 0
v_min_non_paid_invoice 5687 5687 0 0.00 0 4797 17 0 0
v_min_non_paid_realizn 7018 7018 0 0.00 0 8990 18 0 0
v_our_firm 10404 10404 0 0.00 0 10 1 0 0
v_reserve_write_off 19748 19748 0 0.00 0 6984 6 0 0
x_lock_depdocs_on_canc_invoice 11436 11436 0 0.00 0 29305 133 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 14010 398 0 0
x_make_qstorno_1000_1200 5422 5422 0 0.00 1 34934 1209 0 0
x_make_qstorno_1200_2000 5393 5393 0 0.00 4 79393 3241 0 0
x_make_qstorno_2100_3300 462334 462334 0 0.00 0 72748 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-04-2022 16:39:53 to 07-04-2022 16:39:57.

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:20 5 8192000000 8803974096 8816988160 9507584 0.115 3 1 2 0 9652208 64736 79888
14:09:21 28192 8192000000 9907024272 10218053632 986694192 10.749 100 89 178 0 1020254880 10351296 23209392
14:11:07 23798 8192000000 9973577376 10297839616 1054926544 11.408 100 92 184 0 1086740768 8615680 23198544
14:12:42 13692 8192000000 10009247632 10348761088 1094081824 11.781 100 83 166 0 1124325088 10318144 19925120
14:13:58 11937 8192000000 10051997888 10399567872 1137904624 12.196 100 80 160 0 1168490640 11637248 18948768
14:15:11 6108 8192000000 10093077328 10455449600 1184802112 12.635 100 77 153 1 1214259392 13009232 16448048
14:16:21 5789 8192000000 10139001408 10507362304 1222850576 12.988 100 81 158 1 1254183872 13422064 17911232
14:17:29 2858 8192000000 10171561568 10549170176 1257464160 13.307 100 80 161 1 1288597648 13151888 17981600
14:18:34 2021 8192000000 10202518416 10582540288 1277784496 13.493 100 93 187 0 1312224464 13802368 20637600
14:19:38 1293 8192000000 10215406400 10597281792 1300344544 13.698 100 95 188 1 1335682384 13921792 21416048
14:20:41 16443 8192000000 10217062016 10611863552 1311126912 13.796 100 70 141 1 1333682576 5936032 16619632
14:21:58 13577 8192000000 10207625072 10623922176 1323524080 13.909 100 17 34 0 1332684144 5471600 3688464
14:23:16 12208 8192000000 10218406736 10634358784 1331440176 13.980 100 19 38 1 1341846384 6271936 4134272
14:24:31 41305 8192000000 10225779648 10650558464 1336800672 14.029 100 23 44 1 1346873088 5136624 4935792
14:26:13 8968 8192000000 10220278480 10645889024 1333067648 13.995 100 26 53 2 1345021440 5508064 6445728
14:27:23 9627 8192000000 10226436880 10648215552 1333440176 13.998 100 17 34 0 1346641696 9321216 3880304
14:28:34 72484 8192000000 10192377056 10606878720 1238638400 13.134 93 32 64 2 1252158560 6123568 7396592
14:30:51 7069 8192000000 10150195504 10552233984 1211698880 12.885 95 31 63 2 1225184464 6300368 7185216
14:31:59 10349 8192000000 10141606336 10535813120 1214342944 12.909 96 25 50 0 1229844384 9739472 5761968
14:33:12 13194 8192000000 10153670592 10559553536 1238255408 13.130 97 42 84 1 1255175696 6091088 10829200
14:34:26 23379 8192000000 10165375664 10579136512 1267108608 13.395 99 16 33 1 1275952720 5342848 3501264
14:35:50 6860 8192000000 10183491632 10599297024 1295680608 13.656 100 24 48 1 1306592400 5412176 5499616
14:37:01 7899 8192000000 10200424432 10611724288 1303455040 13.727 100 25 48 1 1315334880 5549168 6330672
14:38:11 12784 8192000000 10221441120 10638172160 1323325552 13.907 100 20 40 0 1336888864 9165632 4397680
14:39:34 75238 8192000000 10252315184 10678562816 1350677456 14.154 100 42 84 1 1367892128 8451776 8762896
14:41:57 60085 8192000000 10277354656 10702462976 1368938272 14.318 100 36 72 0 1386933680 8984304 9011104
14:44:02 16635 8192000000 10283551696 10706358272 1372465792 14.349 99 57 113 1 1400665248 14303040 13896416
14:45:28 22610 8192000000 10305530240 10737090560 1397836384 14.576 100 38 76 0 1420785616 13614016 9335216
14:47:18 5784 8192000000 10329630432 10752122880 1403160400 14.623 100 58 115 1 1437778064 20274576 14343088
14:48:28 8607 8192000000 10321283952 10749870080 1401207088 14.606 100 56 112 0 1435007696 19859104 13941504
14:49:43 4913 8192000000 10357446192 10772492288 1417691024 14.752 100 72 143 0 1458324064 23711680 16921360
14:50:52 7943 8192000000 10360620032 10785034240 1422031232 14.791 100 63 124 1 1468064768 30622160 15411376
14:52:03 2963 8192000000 10345375760 10762350592 1394279536 14.544 99 78 156 1 1447893904 34494064 19120304
14:53:13 2867 8192000000 10348678544 10760048640 1389132976 14.498 99 75 146 3 1440007008 32926160 17947872
14:54:20 4685 8192000000 10350998080 10768035840 1401296256 14.607 100 67 132 1 1453758096 35577568 16884272
14:55:31 10814 8192000000 10316970432 10727669760 1368844272 14.317 98 53 106 1 1418743856 36997072 12902512
14:56:48 10976 8192000000 10325756496 10738372608 1369482096 14.322 100 65 130 1 1431415376 44410736 17522544
14:58:07 4107 8192000000 10330027472 10744504320 1374481520 14.367 100 63 124 1 1435487200 45011168 15994512
14:59:47 3270 8192000000 10346422592 10747232256 1371366656 14.339 100 89 176 1 1442366560 48145120 22854784
15:00:58 13040 8192000000 10317578768 10740723712 1355409536 14.196 99 36 72 0 1410374944 44192960 10772448
15:02:22 3670 8192000000 10322264448 10747789312 1369577136 14.323 100 47 94 4 1429547168 46749712 13220320
15:03:29 12650 8192000000 10319544144 10750062592 1357011296 14.211 100 32 64 0 1424011344 57566112 9433936
15:04:49 21822 8192000000 10294530496 10732761088 1363463488 14.268 100 24 48 3 1407439760 37537360 6438912
15:06:43 18106 8192000000 10290830736 10736529408 1359962304 14.237 100 25 51 0 1398319360 31492912 6864144
15:08:05 90656 8192000000 10283365600 10717319168 1297150864 13.669 94 17 34 0 1310908448 10319232 3438352
15:10:36 107326 8192000000 10277945696 10717847552 1366570992 14.296 100 34 68 0 1382179856 8006864 7602000
15:13:45 65414 8192000000 10286934816 10740465664 1367189840 14.302 99 23 46 2 1389655728 17019728 5446160
15:15:53 94183 8192000000 10288618880 10762305536 1370986736 14.336 99 15 30 0 1398762624 24060240 3715648
15:18:28 87007 8192000000 10292172624 10747076608 1385775792 14.468 100 20 40 1 1403008384 12409776 4822816
15:21:45 104278 8192000000 10284166912 10747674624 1385568688 14.466 100 7 14 1 1396772432 9541056 1662688
15:24:33 36599 8192000000 10297634928 10756653056 1389758720 14.504 100 13 26 0 1405373184 12411232 3203232
15:26:10 58710 8192000000 10283035488 10752024576 1379372256 14.411 99 13 26 1 1396669904 14337088 2960560
15:28:22 51591 8192000000 10300487904 10763567104 1382783424 14.441 99 10 20 0 1394013296 8964464 2265408
15:30:15 42793 8192000000 10298625504 10773811200 1406267632 14.651 100 17 35 2 1421678176 11207664 4202880
15:32:03 37132 8192000000 10313325440 10772049920 1389813568 14.504 99 14 28 1 1404431824 11504096 3114160
15:33:42 75660 8192000000 10317549408 10799316992 1408690752 14.672 100 15 30 0 1435186960 22780512 3715696
15:35:58 62764 8192000000 10316791504 10776268800 1394722416 14.548 99 22 44 0 1413977136 14214976 5039744
15:38:00 70203 8192000000 10310851952 10792742912 1370054960 14.328 97 8 16 0 1395257952 23633696 1569296
15:40:14 84392 8192000000 10291498736 10743640064 1324543808 13.918 94 26 52 2 1340985824 10205040 6236976
15:42:38 58068 8192000000 10303013088 10751418368 1369702320 14.324 98 35 70 2 1392240304 13569232 8968752
15:44:37 62084 8192000000 10275544336 10737790976 1345298704 14.105 97 13 26 0 1360529248 12366752 2863792
15:46:39 83702 8192000000 10250485680 10709348352 1307645040 13.765 95 10 20 2 1318939632 9092704 2201888
15:49:04 43345 8192000000 10260889360 10723753984 1355416144 14.196 99 22 44 1 1373471232 13316784 4738304
15:50:51 51212 8192000000 10277660688 10732826624 1336919632 14.030 97 21 42 0 1351992304 9952832 5119840
15:52:42 57353 8192000000 10255724880 10711040000 1339129424 14.050 98 13 26 0 1363025424 21085296 2810704
15:54:39 56150 8192000000 10245266816 10693873664 1315730320 13.838 98 21 42 0 1333806672 13476160 4600192
15:56:43 97249 8192000000 10262970496 10702987264 1270331648 13.425 94 30 59 2 1287926848 11140624 6454576
16:00:05 93178 8192000000 10258936160 10725494784 1339212352 14.050 99 8 16 0 1364376688 23480000 1684336
16:02:44 80242 8192000000 10246972880 10692222976 1316648128 13.846 97 25 50 0 1331837712 9543344 5646240
16:06:03 35197 8192000000 10249430032 10705674240 1345243184 14.105 99 11 22 0 1360040720 12414704 2382832
16:07:44 57335 8192000000 10271114400 10730938368 1370764624 14.334 100 11 22 0 1380690528 7421008 2504896
16:09:43 59471 8192000000 10256183968 10715131904 1352984608 14.174 99 17 31 1 1364248432 7645040 3618784
16:11:48 64795 8192000000 10278918176 10730799104 1375655808 14.378 100 11 22 1 1386564608 8343600 2565200
16:13:55 15641 8192000000 10283192816 10735599616 1382212000 14.436 100 36 73 0 1396587440 6049040 8326400
16:15:17 29094 8192000000 10299663264 10749906944 1378814368 14.406 99 29 58 1 1395934928 10904032 6216528
16:16:46 61719 8192000000 10293107872 10757562368 1376683808 14.387 99 26 52 1 1399000032 16393152 5923072
16:18:52 27989 8192000000 10320737024 10759827456 1394898176 14.550 100 41 82 1 1416356592 12100464 9357952
16:20:25 103268 8192000000 10297474704 10748203008 1352785152 14.173 97 11 22 0 1370568304 15392992 2390160
16:23:09 83154 8192000000 10298535600 10782162944 1374889632 14.371 99 13 26 1 1397112384 19167680 3055072
16:25:46 141895 8192000000 10283514784 10736865280 1357253344 14.213 97 30 60 0 1376603584 12216576 7133664
16:29:14 63582 8192000000 10286754896 10752946176 1374258528 14.365 98 11 22 0 1388278752 11936288 2083936
16:31:17 53006 8192000000 10268496944 10721882112 1311309200 13.798 95 9 18 0 1320836192 7341184 2185808
16:33:36 70141 8192000000 10285564256 10740170752 1370383776 14.330 99 21 42 1 1392404000 17073024 4947200
16:36:20 68318 8192000000 10270101584 10738896896 1338782944 14.046 97 9 18 0 1352218432 11262544 2172944

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 07-04-2022 16:39:59 to 07-04-2022 16:40:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-04-2022 16:40:14 to 07-04-2022 16:40:16.

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          237887
MON$OLDEST_ACTIVE               239077
MON$OLDEST_SNAPSHOT             239077
MON$NEXT_TRANSACTION            239077
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                       2033504
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.33576 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33576 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33576 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33576 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 ---
:78: 
:79: mscs-test-161.budzdorov.ru    Thu Apr  7 16:38:18 2022
:80:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:81: 
:82: 
:83: mscs-test-161.budzdorov.ru    Thu Apr  7 16:38:18 2022
:84:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:85: 
:86: 
:87: mscs-test-161.budzdorov.ru    Thu Apr  7 16:38:21 2022
:88:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:89: 
:90: 
:91: mscs-test-161.budzdorov.ru    Thu Apr  7 16:38:21 2022
:92:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:93: 
--- end of diff output ---

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

07.04.22 16:40:17. 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-07 16:40:20 No severe PSQL-related problems occured
07.04.22 16:40:21. End of report.