Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.07.2022 01:40
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16176444 kB
MemAvailable 31212964 kB
Buffers 0 kB
Cached 15568748 kB
SwapCached 2492 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-01 01:38:22
DTS_END                         2022-07-01 01:38:22.0340
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/49918
TRN_ID                          245697
ATT_ID                          673
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: 9397.26 18870 1127671
customer order: creation 178.72 359 21446
customer order: refuse 15.20 337 1824
order to supplier: creation 47.18 1205 5661
order to supplier: removal 3.59 95158 431
invoice (draft): creation 47.40 3083 5688
invoice (draft): removal 53.53 745 6424
invoice accept: apply 43.69 16361 5243
invoice accept: cancel 48.48 801 5818
customer reserve: creation 3963.43 164 475612
customer reserve: removal 4693.13 58 563175
realization accept: apply 87.89 165 10547
realization accept: cancel 82.06 95 9847
payment from customer: creation 61.08 97 7330
payment from customer: removal 5.69 65 683
payment to supplier: creation 50.22 102 6026
payment to supplier: removal 3.38 61 406
service: total inventory turnovers 4.80 996 576
service: total monetary turnovers 6.98 23 837
service: aggregate perf. data 0.78 24941 93
service: refresh index statistics 0.03 232576 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-07-2022 01:40:35 to 01-07-2022 01:40:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4877
TEST_TIME 2 3742
TEST_TIME 3 4972
TEST_TIME 4 4689
TEST_TIME 5 4543
TEST_TIME 6 4700
TEST_TIME 7 4792
TEST_TIME 8 5056
TEST_TIME 9 5061
TEST_TIME 10 5333
TEST_TIME 11 5366
TEST_TIME 12 6219
TEST_TIME 13 6032
TEST_TIME 14 6489
TEST_TIME 15 6788
TEST_TIME 16 6872
TEST_TIME 17 7083
TEST_TIME 18 7289
TEST_TIME 19 8234
TEST_TIME 20 8169
TEST_TIME 21 8554
TEST_TIME 22 8748
TEST_TIME 23 9157
TEST_TIME 24 9253
TEST_TIME 25 9550
TEST_TIME 26 9776
TEST_TIME 27 9964
TEST_TIME 28 10735
TEST_TIME 29 10529
TEST_TIME 30 10565
TEST_TIME 31 10537
TEST_TIME 32 10498
TEST_TIME 33 10589
TEST_TIME 34 10493
TEST_TIME 35 10213
TEST_TIME 36 10282
TEST_TIME 37 10170
TEST_TIME 38 10058
TEST_TIME 39 9953
TEST_TIME 40 9956
TEST_TIME 41 10037
TEST_TIME 42 9997
TEST_TIME 43 9897
TEST_TIME 44 9799
TEST_TIME 45 9769
TEST_TIME 46 9774
TEST_TIME 47 9634
TEST_TIME 48 9755
TEST_TIME 49 9616
TEST_TIME 50 9629
TEST_TIME 51 9601
TEST_TIME 52 9612
TEST_TIME 53 9602
TEST_TIME 54 9548
TEST_TIME 55 9555
TEST_TIME 56 9582
TEST_TIME 57 9636
TEST_TIME 58 9594
TEST_TIME 59 9722
TEST_TIME 60 9682
TEST_TIME 61 9729
TEST_TIME 62 9697
TEST_TIME 63 9810
TEST_TIME 64 9837
TEST_TIME 65 9849
TEST_TIME 66 9840
TEST_TIME 67 9973
TEST_TIME 68 9892
TEST_TIME 69 9756
TEST_TIME 70 9722
TEST_TIME 71 9635
TEST_TIME 72 9703
TEST_TIME 73 9642
TEST_TIME 74 9619
TEST_TIME 75 9640
TEST_TIME 76 9599
TEST_TIME 77 9563
TEST_TIME 78 9538
TEST_TIME 79 9491
TEST_TIME 80 9463
TEST_TIME 81 9478
TEST_TIME 82 9480
TEST_TIME 83 9446
TEST_TIME 84 9461
TEST_TIME 85 9540
TEST_TIME 86 9598
TEST_TIME 87 9598
TEST_TIME 88 9624
TEST_TIME 89 9605
TEST_TIME 90 9608
TEST_TIME 91 9696
TEST_TIME 92 9670
TEST_TIME 93 9693
TEST_TIME 94 9597
TEST_TIME 95 9560
TEST_TIME 96 9535
TEST_TIME 97 9502
TEST_TIME 98 9466
TEST_TIME 99 9435
TEST_TIME 100 9425
TEST_TIME 101 9438
TEST_TIME 102 9431
TEST_TIME 103 9433
TEST_TIME 104 9438
TEST_TIME 105 9446
TEST_TIME 106 9500
TEST_TIME 107 9502
TEST_TIME 108 9586
TEST_TIME 109 9557
TEST_TIME 110 9563
TEST_TIME 111 9588
TEST_TIME 112 9575
TEST_TIME 113 9494
TEST_TIME 114 9501
TEST_TIME 115 9481
TEST_TIME 116 9479
TEST_TIME 117 9447
TEST_TIME 118 9417
TEST_TIME 119 9457
TEST_TIME 120 9399

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-07-2022 01:40:39 to 01-07-2022 01:40:42.

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 1568207 1568207 0 0.00 0 25466 5 0 0
doc_list_biud 1568204 1568204 0 0.00 0 52671 26 0 0
sp_add_invoice_to_stock 5369 5243 126 2.34 36 153508 16361 0 126
sp_cancel_adding_invoice 5818 5818 0 0.00 3 98321 801 0 0
sp_cancel_client_order 1824 1824 0 0.00 4 9632 337 0 0
sp_cancel_customer_reserve 563175 563175 0 0.00 0 52673 59 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 2 3633 66 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 2 3498 61 0 0
sp_cancel_supplier_invoice 6431 6424 7 0.10 1 28541 746 0 7
sp_cancel_supplier_order 431 431 0 0.00 8 962600 95158 0 0
sp_cancel_write_off 9847 9847 0 0.00 1 12401 96 0 0
sp_client_order 21446 21446 0 0.00 3 18174 360 0 0
sp_customer_reserve 475612 475612 0 0.00 0 81904 165 0 0
sp_fill_shopping_cart 32790 32790 0 0.00 1 10956 34 0 0
sp_fill_shopping_cart_clo_res 475608 475608 0 0.00 0 14037 14 0 0
sp_get_clo_for_invoice 5239 5239 0 0.00 0 26186 338 0 0
sp_kill_cost_storno 16754 16754 0 0.00 0 7558 47 0 0
sp_kill_qstorno_ret_qs2qd 1143052 1143052 0 0.00 0 52668 32 0 0
sp_kill_qty_storno 603305 603305 0 0.00 0 52670 68 0 0
sp_lock_selected_doc 21721 21721 0 0.00 0 7208 5 0 0
sp_make_cost_storno 29142 29142 0 0.00 0 17874 41 0 0
sp_multiply_rows_for_pdistr 29142 29142 0 0.00 0 11279 24 0 0
sp_multiply_rows_for_qdistr 21445 21445 0 0.00 0 14644 166 0 0
sp_pay_from_customer 7330 7330 0 0.00 4 13814 98 0 0
sp_pay_to_supplier 6026 6026 0 0.00 5 12556 103 0 0
sp_payment_common 13356 13356 0 0.00 1 13797 22 0 0
sp_qd_handle_on_cancel_clo 1824 1824 0 0.00 0 9623 296 0 0
sp_qd_handle_on_invoice_upd_sts 11057 11057 0 0.00 0 16355 301 0 0
sp_qd_handle_on_reserve_upd_sts 10547 10547 0 0.00 0 13585 57 0 0
sp_reserve_write_off 10547 10547 0 0.00 6 17895 166 0 0
sp_supplier_invoice 5688 5688 0 0.00 6 82346 3083 0 0
sp_supplier_order 5661 5661 0 0.00 4 43173 1206 0 0
srv_aggregate_perf_data 93 93 0 0.00 2270 543090 24942 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2731 142285 43839 0 0
srv_find_qd_qs_mism 1078537 1078537 0 0.00 0 25465 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 29 39659 996 0 0
srv_make_money_saldo 837 837 0 0.00 1 856 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 107290 420037 232576 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 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 5243 5243 0 0.00 0 26932 169 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 3577 82 0 0
t$perf-norm:sp_cancel_client_order 1824 1824 0 0.00 0 9582 17 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 1593 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1202 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 2309 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 10757 61 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 28358 1763 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 2150 9 0 0
t$perf-norm:sp_client_order 21446 21446 0 0.00 0 21906 19 0 0
t$perf-norm:sp_customer_reserve 1091 1091 0 0.00 0 916 3 0 0
t$perf-norm:sp_pay_from_customer 7330 7330 0 0.00 0 6311 9 0 0
t$perf-norm:sp_pay_to_supplier 6026 6026 0 0.00 0 9196 11 0 0
t$perf-norm:sp_reserve_write_off 10547 10547 0 0.00 0 10631 12 0 0
t$perf-norm:sp_supplier_invoice 5688 5688 0 0.00 0 9269 22 0 0
t$perf-norm:sp_supplier_order 5661 5661 0 0.00 0 3643 14 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 1339 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 52 2 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 2127 20 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 2571 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 56 16 0 0
v_add_invoice_to_stock 10728 10476 252 2.34 0 14617 20 0 252
v_all_customers 38896 38896 0 0.00 0 15 0 0 0
v_all_suppliers 31410 31410 0 0.00 0 12 1 0 0
v_all_wares 42890 42890 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 2543 8 0 0
v_cancel_client_order 3648 3648 0 0.00 0 4058 8 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 2611 8 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 1714 5 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 3483 11 0 0
v_cancel_supplier_invoice 836 822 14 1.67 0 2789 20 0 14
v_cancel_supplier_order 862 862 0 0.00 0 2529 8 0 0
v_cancel_write_off 2096 2096 0 0.00 0 3202 14 0 0
v_max_id_clo_ord 5659 5659 0 0.00 0 207 1 0 0
v_max_id_clo_res 1091 1091 0 0.00 0 2015 13 0 0
v_max_id_ord_sup 5684 5684 0 0.00 0 3299 3 0 0
v_max_non_paid_invoice 1664 1664 0 0.00 0 138 2 0 0
v_max_non_paid_realizn 3947 3947 0 0.00 0 2307 4 0 0
v_min_id_clo_ord 5659 5659 0 0.00 0 2799 5 0 0
v_min_id_clo_res 1091 1091 0 0.00 0 10376 42 0 0
v_min_id_ord_sup 5684 5684 0 0.00 0 4974 7 0 0
v_min_non_paid_invoice 6026 6026 0 0.00 0 12491 15 0 0
v_min_non_paid_realizn 7330 7330 0 0.00 0 10238 17 0 0
v_our_firm 10760 10760 0 0.00 0 13 1 0 0
v_reserve_write_off 21094 21094 0 0.00 0 8029 9 0 0
x_lock_depdocs_on_canc_invoice 11831 11831 0 0.00 0 20732 112 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 10916 428 0 0
x_make_qstorno_1000_1200 5661 5661 0 0.00 1 43133 1136 0 0
x_make_qstorno_1200_2000 5688 5688 0 0.00 2 82337 2962 0 0
x_make_qstorno_2100_3300 475612 475612 0 0.00 0 81902 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-07-2022 01:40:43 to 01-07-2022 01:40:47.

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
23:08:25 14 8192000000 8806687888 8820592640 11690288 0.142 3 3 4 0 11874192 76800 107104
23:09:26 34689 8192000000 9920789504 10234408960 994400048 10.824 100 83 166 0 1021400240 5896576 21103616
23:11:10 9824 8192000000 9958533296 10285338624 1044482640 11.308 100 91 182 0 1076756016 8734320 23539056
23:12:29 16233 8192000000 10001128512 10334781440 1080880240 11.656 100 86 172 0 1112511744 10604512 21026992
23:13:48 6971 8192000000 10039805344 10385346560 1119611248 12.023 100 77 154 0 1149134080 11161504 18361328
23:14:59 4260 8192000000 10090750128 10449076224 1174871568 12.542 100 84 168 0 1206253856 12156832 19225456
23:16:08 2893 8192000000 10127593904 10494189568 1213231888 12.899 100 87 172 1 1245963168 13441296 19289984
23:17:14 1888 8192000000 10160710400 10534014976 1246050816 13.202 100 84 164 0 1277812512 13704352 18057344
23:18:18 1602 8192000000 10185329744 10565627904 1275739008 13.474 100 84 166 0 1307796784 13489424 18568352
23:19:21 1196 8192000000 10202375984 10590892032 1293969968 13.640 100 84 169 0 1324271136 11467024 18834144
23:20:24 11128 8192000000 10214103712 10608046080 1310160032 13.788 100 77 156 2 1339810656 12543424 17107200
23:21:35 6645 8192000000 10197997920 10610929664 1313138240 13.815 100 19 36 0 1322775712 5282688 4354784
23:22:42 7425 8192000000 10204944992 10620235776 1318069056 13.859 100 22 44 0 1328094960 4980496 5045408
23:23:51 9327 8192000000 10208831456 10626162688 1322798736 13.902 100 11 22 0 1330281008 5316272 2166000
23:25:01 51713 8192000000 10228263888 10645536768 1329114864 13.959 100 9 16 0 1336514512 4689776 2709872
23:26:58 5668 8192000000 10224442720 10642018304 1321917648 13.894 99 26 52 0 1334237856 6857360 5462848
23:28:05 4694 8192000000 10210810048 10630934528 1307838480 13.766 99 14 26 0 1320949376 10247280 2863616
23:29:09 10951 8192000000 10160151888 10573721600 1226402256 13.021 94 15 30 0 1235987200 5655200 3929744
23:30:22 17017 8192000000 10184667712 10582990848 1271156944 13.432 99 34 69 3 1283544448 4143488 8244016
23:31:40 5676 8192000000 10148260576 10547359744 1243075632 13.175 99 34 67 1 1256874144 6134656 7663856
23:32:48 9047 8192000000 10133678384 10532696064 1210528560 12.874 97 24 46 0 1221252624 5502096 5221968
23:33:58 8868 8192000000 10158568880 10566184960 1266908480 13.393 100 27 52 0 1279040304 5668256 6463568
23:35:14 34433 8192000000 10165273296 10578079744 1275974256 13.476 100 36 73 0 1290331472 5628016 8729200
23:36:51 6133 8192000000 10182753296 10599096320 1294545312 13.646 100 25 50 0 1306338352 6244448 5548592
23:37:59 12975 8192000000 10207283840 10618290176 1309725760 13.784 100 15 30 0 1318440064 5125232 3589072
23:39:24 9767 8192000000 10247209888 10653904896 1334839344 14.011 100 51 100 1 1356883216 10707040 11336832
23:40:35 46748 8192000000 10257182320 10673094656 1346382832 14.115 100 39 77 0 1368057792 13124768 8550192
23:42:27 23765 8192000000 10261666832 10685829120 1359940992 14.237 100 38 75 0 1382711072 14538784 8231296
23:43:56 6774 8192000000 10298703808 10712449024 1375200816 14.374 100 65 128 1 1407143408 17966016 13976576
23:45:07 9862 8192000000 10303657184 10726735872 1389131376 14.498 100 52 101 0 1419255904 18509424 11615104
23:46:21 4549 8192000000 10318272560 10736545792 1391905328 14.523 100 51 101 0 1427388544 23762960 11720256
23:47:29 5959 8192000000 10317008416 10735738880 1382289376 14.437 99 55 108 1 1419508800 24002096 13217328
23:48:41 6216 8192000000 10336566416 10750160896 1398675056 14.583 100 71 139 2 1447133552 30881376 17577120
23:49:55 6678 8192000000 10332799728 10748964864 1375835856 14.379 98 63 127 1 1426899472 35360064 15703552
23:51:05 3821 8192000000 10342676896 10765090816 1405726928 14.646 100 56 106 1 1450998736 31618992 13652816
23:52:24 2731 8192000000 10325464208 10734182400 1370784032 14.334 99 73 143 1 1427338992 38689504 17865456
23:53:37 9334 8192000000 10337691728 10760519680 1393238784 14.535 100 48 95 0 1446500208 41294928 11966496
23:54:51 4451 8192000000 10335354256 10744807424 1378269360 14.401 100 73 145 2 1438400256 41489568 18641328
23:56:00 10842 8192000000 10348263344 10768506880 1377229216 14.392 99 57 112 0 1443039168 51557744 14252208
23:57:16 14040 8192000000 10332750912 10757652480 1366614304 14.297 98 41 80 1 1426127584 48074208 11439072
23:58:33 12300 8192000000 10314924688 10739564544 1358308112 14.222 99 33 66 0 1411111568 43937696 8865760
23:59:50 19430 8192000000 10291476624 10726735872 1320182448 13.878 97 32 64 1 1374758560 45908416 8667696
00:01:14 9687 8192000000 10312008032 10737930240 1356375808 14.205 99 43 85 0 1412576944 45602848 10598288
00:02:26 14075 8192000000 10300759072 10733916160 1356938624 14.210 99 34 68 0 1406817936 40448832 9430480
00:03:41 10758 8192000000 10303701520 10741448704 1367605728 14.306 100 25 50 0 1412897424 38618128 6673568
00:05:04 40571 8192000000 10306425280 10744565760 1358040736 14.220 100 19 38 1 1404193408 40504592 5648080
00:06:48 33014 8192000000 10271869632 10703233024 1329722240 13.965 98 10 20 1 1345238400 13111536 2404624
00:08:33 79359 8192000000 10287622480 10727522304 1336488576 14.026 97 20 40 0 1351799328 10983312 4327440
00:10:53 42950 8192000000 10289592096 10719707136 1359558176 14.233 99 33 64 1 1386968864 20385056 7025632
00:12:36 105233 8192000000 10279069584 10711502848 1345158080 14.104 99 37 74 0 1363122768 9517136 8447552
00:15:23 62288 8192000000 10282923984 10725658624 1374583520 14.368 100 16 32 0 1387345104 8853776 3907808
00:17:28 62344 8192000000 10309092336 10757505024 1396349456 14.562 100 25 50 1 1417376288 14174352 6852480
00:19:44 39937 8192000000 10281815504 10729353216 1364199184 14.275 98 22 44 0 1379242672 9699680 5343808
00:21:25 56740 8192000000 10304199264 10761334784 1398349488 14.580 100 15 30 0 1412456256 10720432 3386336
00:23:32 77586 8192000000 10305940064 10769842176 1405147280 14.641 100 16 32 0 1423625616 14745408 3732928
00:25:55 68139 8192000000 10285825888 10754523136 1357972144 14.219 97 28 52 1 1378090880 14422560 5696176
00:28:04 31778 8192000000 10287560064 10757677056 1390387792 14.509 100 21 42 0 1409029376 13947392 4694192
00:29:46 55047 8192000000 10285168080 10751684608 1379399200 14.411 99 10 20 2 1392621248 10809072 2412976
00:31:42 48379 8192000000 10284466112 10748854272 1367073424 14.301 98 12 24 0 1382050672 12279120 2698128
00:33:31 46181 8192000000 10291244480 10749444096 1344767536 14.100 96 18 36 0 1359081616 10243360 4070720
00:35:18 45446 8192000000 10265781904 10709078016 1301034048 13.705 93 12 24 0 1317250288 13553760 2662480
00:37:05 59216 8192000000 10284607680 10753875968 1366835584 14.299 99 8 16 0 1377602432 9145648 1621200
00:39:04 55245 8192000000 10267713568 10718187520 1317665216 13.856 96 8 16 0 1326489008 7424752 1399040
00:41:04 62047 8192000000 10274057712 10736656384 1354095968 14.184 99 14 28 0 1371216976 13826896 3294112
00:43:07 32098 8192000000 10275195392 10719436800 1355326480 14.195 99 32 64 1 1371319280 9580608 6412192
00:44:45 45793 8192000000 10280307744 10741805056 1348899616 14.138 98 25 50 1 1368897632 14312176 5685840
00:46:31 142283 8192000000 10273107888 10727309312 1340909552 14.066 98 20 40 0 1359971984 14855392 4207040
00:50:10 71151 8192000000 10250929328 10703097856 1328890304 13.957 98 18 34 2 1344734592 11574416 4269872
00:52:34 55465 8192000000 10257546400 10715672576 1350059312 14.148 99 14 28 0 1368666272 15279872 3327088
00:54:30 65139 8192000000 10265023216 10716712960 1364049920 14.274 100 14 28 0 1376123856 8755888 3318048
00:56:44 43032 8192000000 10256927056 10712346624 1364375312 14.277 100 8 16 1 1373704864 7573040 1756512
00:58:27 61301 8192000000 10236899008 10691215360 1295804272 13.657 95 10 20 0 1306895840 9040016 2051552
01:00:31 50085 8192000000 10283767936 10738143232 1369460464 14.322 100 34 68 0 1393322368 15901056 7960848
01:02:22 48462 8192000000 10279809936 10726543360 1336398304 14.025 97 23 44 0 1360865552 19053536 5413712
01:04:53 70725 8192000000 10274213632 10727092224 1328053120 13.950 97 17 34 0 1344378480 12488192 3837168
01:07:10 20704 8192000000 10291603152 10724667392 1345262528 14.105 98 58 117 0 1369453504 10996608 13194368
01:08:53 97117 8192000000 10296480672 10755444736 1365762688 14.289 99 19 38 0 1386224464 16766144 3695632
01:11:31 80913 8192000000 10285357552 10733383680 1376740064 14.387 100 27 54 0 1392980384 9947152 6293168
01:13:55 44062 8192000000 10275039712 10741047296 1373020128 14.354 99 6 8 0 1380858560 7120272 718160
01:15:42 51959 8192000000 10290204304 10757029888 1391796560 14.522 100 2 4 0 1398316576 6431200 88816
01:17:45 34293 8192000000 10303381600 10765221888 1372606208 14.350 98 37 74 0 1393169696 12444672 8118816
01:19:24 50310 8192000000 10293876752 10753875968 1377498272 14.394 99 17 35 0 1395507280 13953312 4055696
01:21:18 64642 8192000000 10306146240 10764824576 1363791632 14.271 97 18 36 0 1385339520 17328944 4218944
01:23:52 65783 8192000000 10275424464 10729701376 1320733808 13.883 95 18 36 0 1340595264 15424576 4436880
01:26:02 33805 8192000000 10291850992 10742370304 1338532752 14.044 97 17 34 1 1359533552 17152048 3848752
01:27:38 22259 8192000000 10276141248 10719092736 1339583600 14.054 98 40 75 1 1355976192 7846144 8546448
01:29:03 10006 8192000000 10297635280 10729123840 1368493312 14.314 100 45 90 1 1387377760 8522192 10362256
01:30:13 113467 8192000000 10266253968 10708918272 1310997472 13.795 96 15 30 0 1324891200 10482928 3410800
01:33:08 66233 8192000000 10263266960 10721443840 1326132384 13.932 98 29 58 0 1354754000 22176160 6445456
01:36:00 105091 8192000000 10259244048 10709340160 1315995168 13.840 97 11 22 0 1333615552 14952640 2667744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-07-2022 01:40:49 to 01-07-2022 01:41:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 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 01-07-2022 01:41:05 to 01-07-2022 01:41:07.

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          244431
MON$OLDEST_ACTIVE               246128
MON$OLDEST_SNAPSHOT             246128
MON$NEXT_TRANSACTION            246128
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                       2036960
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.33607 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33607 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33607 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33607 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 ---
:29: 
:30: mscs-test-161.budzdorov.ru    Fri Jul  1 01:38:30 2022
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Fri Jul  1 01:38:30 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Fri Jul  1 01:38:34 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Fri Jul  1 01:38:34 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
--- end of diff output ---

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

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