Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.06.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 16228240 kB
MemAvailable 31180856 kB
Buffers 0 kB
Cached 15461152 kB
SwapCached 2496 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-06-25 22:38:20
DTS_END                         2022-06-25 22:38:20.8810
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/55770
TRN_ID                          238412
ATT_ID                          700
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: 9257.48 20180 1110897
customer order: creation 170.52 378 20462
customer order: refuse 14.06 309 1687
order to supplier: creation 44.98 1235 5397
order to supplier: removal 3.48 88540 418
invoice (draft): creation 46.18 3484 5541
invoice (draft): removal 54.81 725 6577
invoice accept: apply 40.64 16893 4877
invoice accept: cancel 48.98 693 5878
customer reserve: creation 3728.73 169 447447
customer reserve: removal 4807.82 50 576938
realization accept: apply 81.69 169 9803
realization accept: cancel 88.16 104 10579
payment from customer: creation 56.71 126 6805
payment from customer: removal 5.94 69 713
payment to supplier: creation 49.07 142 5888
payment to supplier: removal 3.27 136 392
service: total inventory turnovers 4.68 978 561
service: total monetary turnovers 6.84 33 821
service: aggregate perf. data 0.91 18995 109
service: refresh index statistics 0.03 270377 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-06-2022 22:39:37 to 25-06-2022 22:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2992
TEST_TIME 2 3767
TEST_TIME 3 4772
TEST_TIME 4 4721
TEST_TIME 5 5036
TEST_TIME 6 4825
TEST_TIME 7 4757
TEST_TIME 8 5265
TEST_TIME 9 5041
TEST_TIME 10 5478
TEST_TIME 11 6388
TEST_TIME 12 6307
TEST_TIME 13 6536
TEST_TIME 14 6597
TEST_TIME 15 7239
TEST_TIME 16 7078
TEST_TIME 17 7541
TEST_TIME 18 7879
TEST_TIME 19 7812
TEST_TIME 20 8108
TEST_TIME 21 8673
TEST_TIME 22 9142
TEST_TIME 23 9263
TEST_TIME 24 9533
TEST_TIME 25 9575
TEST_TIME 26 10113
TEST_TIME 27 10042
TEST_TIME 28 10129
TEST_TIME 29 10127
TEST_TIME 30 10078
TEST_TIME 31 10124
TEST_TIME 32 10166
TEST_TIME 33 10146
TEST_TIME 34 10094
TEST_TIME 35 10187
TEST_TIME 36 9997
TEST_TIME 37 10042
TEST_TIME 38 9840
TEST_TIME 39 9774
TEST_TIME 40 9722
TEST_TIME 41 9920
TEST_TIME 42 9794
TEST_TIME 43 9797
TEST_TIME 44 9675
TEST_TIME 45 9570
TEST_TIME 46 9560
TEST_TIME 47 9574
TEST_TIME 48 9500
TEST_TIME 49 9487
TEST_TIME 50 9558
TEST_TIME 51 9478
TEST_TIME 52 9529
TEST_TIME 53 9508
TEST_TIME 54 9666
TEST_TIME 55 9601
TEST_TIME 56 9620
TEST_TIME 57 9696
TEST_TIME 58 9785
TEST_TIME 59 9772
TEST_TIME 60 9845
TEST_TIME 61 9836
TEST_TIME 62 9793
TEST_TIME 63 9650
TEST_TIME 64 9549
TEST_TIME 65 9616
TEST_TIME 66 9607
TEST_TIME 67 9507
TEST_TIME 68 9445
TEST_TIME 69 9384
TEST_TIME 70 9404
TEST_TIME 71 9308
TEST_TIME 72 9332
TEST_TIME 73 9304
TEST_TIME 74 9286
TEST_TIME 75 9330
TEST_TIME 76 9357
TEST_TIME 77 9289
TEST_TIME 78 9330
TEST_TIME 79 9371
TEST_TIME 80 9451
TEST_TIME 81 9441
TEST_TIME 82 9479
TEST_TIME 83 9560
TEST_TIME 84 9526
TEST_TIME 85 9555
TEST_TIME 86 9529
TEST_TIME 87 9507
TEST_TIME 88 9466
TEST_TIME 89 9466
TEST_TIME 90 9434
TEST_TIME 91 9362
TEST_TIME 92 9290
TEST_TIME 93 9382
TEST_TIME 94 9380
TEST_TIME 95 9403
TEST_TIME 96 9356
TEST_TIME 97 9380
TEST_TIME 98 9390
TEST_TIME 99 9389
TEST_TIME 100 9370
TEST_TIME 101 9404
TEST_TIME 102 9457
TEST_TIME 103 9444
TEST_TIME 104 9485
TEST_TIME 105 9451
TEST_TIME 106 9527
TEST_TIME 107 9516
TEST_TIME 108 9445
TEST_TIME 109 9427
TEST_TIME 110 9416
TEST_TIME 111 9401
TEST_TIME 112 9380
TEST_TIME 113 9340
TEST_TIME 114 9305
TEST_TIME 115 9256
TEST_TIME 116 9238
TEST_TIME 117 9309
TEST_TIME 118 9325
TEST_TIME 119 9317
TEST_TIME 120 9258

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-06-2022 22:39:41 to 25-06-2022 22:39:44.

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 1528090 1528090 0 0.00 0 35825 6 0 0
doc_list_biud 1528090 1528090 0 0.00 0 42010 24 0 0
sp_add_invoice_to_stock 4987 4877 110 2.20 22 171704 16894 0 110
sp_cancel_adding_invoice 5878 5878 0 0.00 3 75491 694 0 0
sp_cancel_client_order 1687 1687 0 0.00 4 8895 310 0 0
sp_cancel_customer_reserve 576938 576938 0 0.00 1 42013 50 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 3 3911 70 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 9655 137 0 0
sp_cancel_supplier_invoice 6584 6577 7 0.10 1 15130 726 0 7
sp_cancel_supplier_order 418 418 0 0.00 7 810458 88541 0 0
sp_cancel_write_off 10579 10579 0 0.00 1 12441 104 0 0
sp_client_order 20462 20462 0 0.00 3 35813 379 0 0
sp_customer_reserve 447447 447447 0 0.00 0 69416 170 0 0
sp_fill_shopping_cart 31368 31368 0 0.00 1 11661 36 0 0
sp_fill_shopping_cart_clo_res 447445 447445 0 0.00 0 36566 14 0 0
sp_get_clo_for_invoice 4875 4875 0 0.00 0 16605 305 0 0
sp_kill_cost_storno 17562 17562 0 0.00 0 11454 53 0 0
sp_kill_qstorno_ret_qs2qd 1171450 1171450 0 0.00 0 41991 27 0 0
sp_kill_qty_storno 616755 616755 0 0.00 0 42010 59 0 0
sp_lock_selected_doc 20393 20393 0 0.00 0 8251 4 0 0
sp_make_cost_storno 27371 27371 0 0.00 0 11683 51 0 0
sp_multiply_rows_for_pdistr 27371 27371 0 0.00 0 24618 35 0 0
sp_multiply_rows_for_qdistr 20462 20462 0 0.00 0 15664 176 0 0
sp_pay_from_customer 6805 6805 0 0.00 4 14705 126 0 0
sp_pay_to_supplier 5888 5888 0 0.00 4 24636 142 0 0
sp_payment_common 12693 12693 0 0.00 1 9687 34 0 0
sp_qd_handle_on_cancel_clo 1687 1687 0 0.00 0 8891 285 0 0
sp_qd_handle_on_invoice_upd_sts 10753 10753 0 0.00 0 19105 293 0 0
sp_qd_handle_on_reserve_upd_sts 9803 9803 0 0.00 0 23332 50 0 0
sp_reserve_write_off 9803 9803 0 0.00 6 23384 169 0 0
sp_supplier_invoice 5541 5541 0 0.00 6 80436 3485 0 0
sp_supplier_order 5397 5397 0 0.00 5 44388 1235 0 0
srv_aggregate_perf_data 109 109 0 0.00 2522 274395 18995 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 4795 138704 49940 0 0
srv_find_qd_qs_mism 1065540 1065540 0 0.00 0 35824 5 0 0
srv_make_invnt_saldo 561 561 0 0.00 48 42766 978 0 0
srv_make_money_saldo 821 821 0 0.00 1 11149 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 220840 303167 270377 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4877 4877 0 0.00 0 15708 162 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 0 7842 110 0 0
t$perf-norm:sp_cancel_client_order 1687 1687 0 0.00 0 11716 16 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 3099 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 1892 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 4782 43 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 697 13 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 16774 1554 0 0
t$perf-norm:sp_cancel_write_off 1032 1032 0 0.00 0 6541 15 0 0
t$perf-norm:sp_client_order 20462 20462 0 0.00 0 5487 15 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 5794 13 0 0
t$perf-norm:sp_pay_from_customer 6805 6805 0 0.00 0 5077 10 0 0
t$perf-norm:sp_pay_to_supplier 5888 5888 0 0.00 0 2639 6 0 0
t$perf-norm:sp_reserve_write_off 9803 9803 0 0.00 0 20067 12 0 0
t$perf-norm:sp_supplier_invoice 5541 5541 0 0.00 0 7221 20 0 0
t$perf-norm:sp_supplier_order 5397 5397 0 0.00 0 11948 22 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1234 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 255 13 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 4003 27 0 0
t$perf-norm:srv_make_money_saldo 821 821 0 0.00 0 652 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9970 9750 220 2.20 0 4925 19 0 220
v_all_customers 37202 37202 0 0.00 0 13 0 0 0
v_all_suppliers 30498 30498 0 0.00 0 41 1 0 0
v_all_wares 40924 40924 0 0.00 0 13 1 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 2525 11 0 0
v_cancel_client_order 3374 3374 0 0.00 0 1737 5 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 3902 9 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 2526 8 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 2008 7 0 0
v_cancel_supplier_invoice 812 798 14 1.72 0 6218 23 0 14
v_cancel_supplier_order 836 836 0 0.00 0 4485 16 0 0
v_cancel_write_off 2064 2064 0 0.00 0 2356 15 0 0
v_max_id_clo_ord 5397 5397 0 0.00 0 249 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 2301 12 0 0
v_max_id_ord_sup 5509 5509 0 0.00 0 5819 7 0 0
v_max_non_paid_invoice 1544 1544 0 0.00 0 3060 5 0 0
v_max_non_paid_realizn 3681 3681 0 0.00 0 4191 5 0 0
v_min_id_clo_ord 5397 5397 0 0.00 0 2572 6 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 1529 7 0 0
v_min_id_ord_sup 5508 5508 0 0.00 0 10322 10 0 0
v_min_non_paid_invoice 5888 5888 0 0.00 0 9287 23 0 0
v_min_non_paid_realizn 6805 6805 0 0.00 0 9530 29 0 0
v_our_firm 9970 9970 0 0.00 0 14 1 0 0
v_reserve_write_off 19606 19606 0 0.00 0 3866 8 0 0
x_lock_depdocs_on_canc_invoice 12056 12056 0 0.00 0 21939 100 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 16154 309 0 0
x_make_qstorno_1000_1200 5397 5397 0 0.00 2 44382 1164 0 0
x_make_qstorno_1200_2000 5541 5541 0 0.00 2 74049 3344 0 0
x_make_qstorno_2100_3300 447447 447447 0 0.00 0 69415 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-06-2022 22:39:45 to 25-06-2022 22:39:49.

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:24 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:25 24576 8192000000 9901885888 10213793792 983134944 10.715 100 87 174 0 1012724880 6215776 23374160
20:10:53 31264 8192000000 9950548208 10274439168 1030516656 11.173 100 86 172 0 1061384752 8242400 22625696
20:12:34 18512 8192000000 9985174464 10322214912 1069662688 11.549 100 84 168 1 1100666352 9427680 21575984
20:13:54 10605 8192000000 10047074528 10399641600 1135939040 12.177 100 83 166 3 1165836384 10320464 19576880
20:15:08 9292 8192000000 10080031936 10439536640 1167625232 12.475 100 79 157 0 1197116896 11975104 17516560
20:16:19 6864 8192000000 10127347360 10494242816 1212693328 12.894 100 84 168 0 1244743840 13173824 18876688
20:17:29 6049 8192000000 10142959264 10519875584 1238500080 13.132 100 76 152 1 1268830080 14152432 16177568
20:18:37 2244 8192000000 10189558256 10568749056 1272520784 13.445 100 84 168 0 1305454912 13886896 19047232
20:19:39 27136 8192000000 10202744496 10597666816 1296600064 13.664 100 58 116 0 1318700896 7448576 14652256
20:21:07 3976 8192000000 10202429680 10606551040 1307531872 13.764 100 35 70 2 1322370528 6049040 8789616
20:22:11 8913 8192000000 10199520432 10617262080 1315000128 13.831 100 16 32 0 1324957872 6436800 3520944
20:23:21 69812 8192000000 10227166464 10635161600 1323092320 13.905 100 34 65 0 1336238736 4959680 8186736
20:25:34 10912 8192000000 10226858688 10643357696 1331661840 13.982 100 28 56 0 1345217984 6943696 6612448
20:26:52 7777 8192000000 10236075440 10649153536 1334128800 14.004 100 27 51 3 1345894128 5550176 6215152
20:28:00 20708 8192000000 10220035904 10638979072 1315417632 13.835 99 18 35 2 1324500976 4526864 4556480
20:30:09 7057 8192000000 10147149360 10553634816 1214955840 12.915 96 14 28 0 1223648976 5418512 3274624
20:31:20 6477 8192000000 10137411056 10537910272 1216288432 12.927 97 20 41 1 1226860976 5338480 5234064
20:32:27 11596 8192000000 10169377632 10564120576 1230280320 13.057 97 24 48 2 1242056624 5618112 6158192
20:33:40 12489 8192000000 10153313136 10566369280 1272646656 13.446 100 41 82 0 1289596688 6113136 10836896
20:34:56 10612 8192000000 10180329744 10594922496 1292343040 13.626 100 24 47 1 1303366144 5435280 5587824
20:36:07 7390 8192000000 10202064960 10612486144 1311171920 13.797 100 21 42 0 1321519008 4877184 5469904
20:37:22 40572 8192000000 10208898896 10626740224 1314744368 13.829 100 32 64 1 1328072928 5326384 8002176
20:39:16 41529 8192000000 10237915616 10654547968 1337537360 14.035 100 43 85 1 1355209168 7556816 10114992
20:41:08 13397 8192000000 10260683552 10682372096 1363834928 14.272 100 38 76 0 1381200960 8818912 8547120
20:42:33 22195 8192000000 10283568944 10709512192 1378037712 14.399 100 37 72 3 1395929744 10259376 7632656
20:43:58 13892 8192000000 10298202896 10720436224 1383838784 14.451 100 49 97 1 1406086400 11644864 10602752
20:45:36 35060 8192000000 10308764848 10742272000 1390153408 14.507 99 52 104 3 1419854832 17248896 12452528
20:47:23 6281 8192000000 10327264896 10750496768 1405459904 14.644 100 54 107 1 1437358096 19169808 12728384
20:48:34 7725 8192000000 10343506704 10763890688 1406632528 14.654 99 63 125 1 1443657072 21490448 15534096
20:49:49 9104 8192000000 10357152896 10781118464 1427149520 14.836 100 52 102 2 1460698400 20711216 12837664
20:51:04 4793 8192000000 10369919520 10786185216 1426480960 14.830 100 89 177 0 1475196528 27370656 21344912
20:52:14 5822 8192000000 10340310128 10759630848 1386529712 14.475 97 64 130 2 1431158224 28733920 15894592
20:53:27 5148 8192000000 10349572800 10778898432 1415882784 14.736 100 55 108 1 1463242848 34039792 13320272
20:54:39 6475 8192000000 10350150016 10769559552 1398777600 14.584 99 67 133 1 1450679312 34931600 16970112
20:55:51 12644 8192000000 10314318272 10738634752 1380373120 14.420 99 38 76 1 1421013184 30168768 10471296
20:57:10 13203 8192000000 10328291488 10757640192 1382539504 14.439 99 36 72 1 1426539552 34362368 9637680
20:58:26 12377 8192000000 10328586928 10759442432 1384290624 14.455 100 36 72 0 1433461184 39220176 9950384
20:59:42 19664 8192000000 10296237920 10729283584 1324428512 13.917 96 32 63 1 1370065216 37349888 8286816
21:01:13 9862 8192000000 10276514336 10709532672 1354092944 14.184 100 32 64 2 1384623632 22507904 8022784
21:02:27 28886 8192000000 10271287200 10714533888 1348611744 14.135 100 18 36 0 1381096848 26865184 5619920
21:03:59 24791 8192000000 10257210704 10699743232 1321974992 13.895 98 17 34 1 1354822384 27813328 5034064
21:05:37 36253 8192000000 10243139856 10675834880 1314429808 13.826 98 30 59 3 1331397488 10341952 6625728
21:07:17 81020 8192000000 10263807792 10704355328 1328565360 13.954 98 17 32 1 1347514016 15593232 3355424
21:09:44 31147 8192000000 10264643856 10685652992 1350059936 14.148 99 34 67 1 1364717696 7393072 7264688
21:11:25 41025 8192000000 10281082512 10713788416 1349122592 14.140 99 16 31 3 1364664944 11337056 4205296
21:13:07 138703 8192000000 10280670336 10720243712 1365412128 14.286 99 18 36 0 1383584144 13978656 4193360
21:16:29 73957 8192000000 10297186384 10733887488 1361088512 14.247 99 15 30 0 1379193328 14755472 3349344
21:18:44 126176 8192000000 10297020144 10751401984 1369954240 14.327 99 18 37 0 1388604656 14386096 4264320
21:21:50 100083 8192000000 10303508192 10762039296 1398310032 14.580 100 8 16 0 1415988304 15606192 2072080
21:24:34 71884 8192000000 10297185184 10757398528 1382626240 14.440 99 12 24 0 1405308720 19802432 2880048
21:26:50 57252 8192000000 10307033456 10778140672 1401092832 14.605 100 11 22 0 1419942304 15886800 2962672
21:29:02 45700 8192000000 10329232496 10783752192 1410257744 14.686 100 15 29 3 1430453472 16512400 3683328
21:30:51 56887 8192000000 10321102368 10782539776 1389972832 14.506 98 18 37 2 1410885440 16467792 4444816
21:32:49 92701 8192000000 10329317856 10781106176 1374219552 14.365 97 32 64 0 1394814960 13727776 6867632
21:35:23 49625 8192000000 10318920304 10780233728 1368744032 14.316 97 14 28 0 1383798992 11867776 3187184
21:37:16 63805 8192000000 10323948848 10764406784 1363605680 14.270 97 25 50 2 1385952208 16585008 5761520
21:39:29 73637 8192000000 10279245488 10720776192 1263876336 13.366 91 15 30 0 1276518208 9878224 2763648
21:41:44 64423 8192000000 10263731584 10703839232 1323478528 13.908 97 30 61 2 1340769984 10742336 6549120
21:43:50 81231 8192000000 10264060176 10743152640 1364538608 14.278 100 9 18 0 1385085296 18016896 2529792
21:47:18 66703 8192000000 10266146304 10729132032 1338537296 14.044 97 21 42 1 1355829200 11905600 5386304
21:49:30 81460 8192000000 10233260368 10695102464 1282976848 13.540 95 15 30 0 1300311712 14054192 3280672
21:51:52 59683 8192000000 10269976128 10718507008 1347028064 14.121 98 19 38 0 1361629952 10249040 4352848
21:53:58 56568 8192000000 10277493632 10738200576 1347341072 14.124 98 22 44 1 1364462432 12416160 4705200
21:56:00 83745 8192000000 10286975104 10740207616 1346424048 14.115 98 21 42 0 1369490608 18464208 4602352
21:58:29 51607 8192000000 10282905648 10724589568 1329523280 13.963 96 25 50 0 1346519472 11850960 5145232
22:00:21 24312 8192000000 10305370864 10755616768 1369679712 14.324 99 40 80 0 1395126576 16411072 9035792
22:01:50 49206 8192000000 10273479424 10728071168 1339634720 14.054 97 9 18 1 1360189536 18801424 1753392
22:03:51 65424 8192000000 10293926592 10743275520 1355295808 14.195 98 20 38 3 1382878160 23283776 4298576
22:06:01 85900 8192000000 10283145584 10740604928 1342414032 14.079 97 30 60 1 1367401872 17977728 7010112
22:08:28 59087 8192000000 10262560432 10722250752 1352399136 14.169 98 18 36 1 1367468304 11247280 3821888
22:10:27 97370 8192000000 10296014560 10772873216 1382622320 14.440 100 10 20 0 1399028624 14377664 2028640
22:13:10 30884 8192000000 10304165712 10764898304 1395730880 14.557 100 28 56 2 1410284048 7523824 7029344
22:14:42 102179 8192000000 10316028400 10778890240 1402197200 14.615 100 32 62 0 1421829280 12451184 7180896
22:17:33 41383 8192000000 10321036336 10774233088 1390007808 14.506 99 27 54 1 1410153520 14076416 6069296
22:19:26 75617 8192000000 10301987600 10758385664 1374995344 14.372 98 20 40 0 1391577792 11876448 4706000
22:21:58 40240 8192000000 10329409456 10784141312 1408867424 14.674 100 30 60 1 1426346128 11242912 6235792
22:23:42 35239 8192000000 10312459840 10769113088 1365078176 14.283 97 45 90 1 1388513040 12922816 10512048
22:25:26 70244 8192000000 10295189984 10753957888 1386764528 14.477 100 11 22 1 1407556224 18289104 2502592
22:28:17 27726 8192000000 10306899664 10760527872 1388695328 14.494 100 36 72 1 1412752608 15892784 8164496
22:29:47 40083 8192000000 10285907728 10749796352 1375023680 14.372 99 14 28 1 1390666592 12675712 2967200
22:31:28 77196 8192000000 10287593632 10742571008 1336888432 14.029 96 20 40 0 1351348544 9441664 5018448
22:33:55 92418 8192000000 10265204512 10736177152 1365510960 14.287 98 14 28 0 1383331984 14347248 3473776
22:36:35 53646 8192000000 10277684752 10741448704 1356440752 14.205 98 13 26 0 1371663264 12031776 3190736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-06-2022 22:39:51 to 25-06-2022 22:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-06-2022 22:40:07 to 25-06-2022 22:40:09.

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          237346
MON$OLDEST_ACTIVE               238777
MON$OLDEST_SNAPSHOT             238777
MON$NEXT_TRANSACTION            238777
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                       2027168
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:428: 
:429: mscs-test-161.budzdorov.ru    Sat Jun 25 22:38:23 2022
:430:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:431: 
:432: 
:433: mscs-test-161.budzdorov.ru    Sat Jun 25 22:38:24 2022
:434:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:435: 
:436: 
:437: mscs-test-161.budzdorov.ru    Sat Jun 25 22:38:28 2022
:438:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:439: 
--- end of diff output ---

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

25.06.22 22:40:10. 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-06-25 22:40:12 No severe PSQL-related problems occured
25.06.22 22:40:13. End of report.