Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.10.2021 19: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15954376 kB
MemAvailable 30765764 kB
Buffers 0 kB
Cached 15763940 kB
SwapCached 4704 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.8
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 2021-10-01 19:37:51
DTS_END                         2021-10-01 19:37:51.8150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(113:22) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36112
TRN_ID                          245808
ATT_ID                          687
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: 9358.83 19639 1123059
customer order: creation 180.98 340 21718
customer order: refuse 14.78 364 1774
order to supplier: creation 48.27 1223 5792
order to supplier: removal 3.23 103932 387
invoice (draft): creation 47.23 3387 5667
invoice (draft): removal 50.89 730 6107
invoice accept: apply 44.70 17819 5364
invoice accept: cancel 46.11 833 5533
customer reserve: creation 4052.88 180 486345
customer reserve: removal 4563.43 58 547611
realization accept: apply 86.91 189 10429
realization accept: cancel 84.05 118 10086
payment from customer: creation 61.66 132 7399
payment from customer: removal 5.88 85 705
payment to supplier: creation 50.80 151 6096
payment to supplier: removal 3.68 87 441
service: total inventory turnovers 5.30 974 636
service: total monetary turnovers 7.15 41 858
service: aggregate perf. data 0.90 18673 108
service: refresh index statistics 0.03 243459 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-10-2021 19:39:45 to 01-10-2021 19:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3821
TEST_TIME 2 3390
TEST_TIME 3 4059
TEST_TIME 4 3908
TEST_TIME 5 3867
TEST_TIME 6 4651
TEST_TIME 7 4730
TEST_TIME 8 5362
TEST_TIME 9 6398
TEST_TIME 10 6250
TEST_TIME 11 6685
TEST_TIME 12 7035
TEST_TIME 13 7232
TEST_TIME 14 7315
TEST_TIME 15 7474
TEST_TIME 16 7684
TEST_TIME 17 8264
TEST_TIME 18 8351
TEST_TIME 19 8471
TEST_TIME 20 8436
TEST_TIME 21 8938
TEST_TIME 22 9062
TEST_TIME 23 9297
TEST_TIME 24 9314
TEST_TIME 25 9711
TEST_TIME 26 9978
TEST_TIME 27 10881
TEST_TIME 28 10951
TEST_TIME 29 10981
TEST_TIME 30 10846
TEST_TIME 31 10781
TEST_TIME 32 10795
TEST_TIME 33 10809
TEST_TIME 34 10765
TEST_TIME 35 10621
TEST_TIME 36 10454
TEST_TIME 37 10296
TEST_TIME 38 10320
TEST_TIME 39 10242
TEST_TIME 40 10153
TEST_TIME 41 10103
TEST_TIME 42 10054
TEST_TIME 43 10060
TEST_TIME 44 10078
TEST_TIME 45 10125
TEST_TIME 46 9995
TEST_TIME 47 9943
TEST_TIME 48 9916
TEST_TIME 49 9894
TEST_TIME 50 9858
TEST_TIME 51 9971
TEST_TIME 52 10065
TEST_TIME 53 10106
TEST_TIME 54 10058
TEST_TIME 55 10043
TEST_TIME 56 10035
TEST_TIME 57 10104
TEST_TIME 58 10180
TEST_TIME 59 10164
TEST_TIME 60 10138
TEST_TIME 61 10168
TEST_TIME 62 10125
TEST_TIME 63 10153
TEST_TIME 64 10264
TEST_TIME 65 10187
TEST_TIME 66 10066
TEST_TIME 67 10103
TEST_TIME 68 10062
TEST_TIME 69 9969
TEST_TIME 70 10020
TEST_TIME 71 9932
TEST_TIME 72 9945
TEST_TIME 73 9921
TEST_TIME 74 9891
TEST_TIME 75 9845
TEST_TIME 76 9798
TEST_TIME 77 9786
TEST_TIME 78 9761
TEST_TIME 79 9736
TEST_TIME 80 9704
TEST_TIME 81 9744
TEST_TIME 82 9760
TEST_TIME 83 9750
TEST_TIME 84 9778
TEST_TIME 85 9800
TEST_TIME 86 9804
TEST_TIME 87 9816
TEST_TIME 88 9836
TEST_TIME 89 9823
TEST_TIME 90 9867
TEST_TIME 91 9852
TEST_TIME 92 9768
TEST_TIME 93 9726
TEST_TIME 94 9660
TEST_TIME 95 9669
TEST_TIME 96 9633
TEST_TIME 97 9584
TEST_TIME 98 9541
TEST_TIME 99 9532
TEST_TIME 100 9561
TEST_TIME 101 9557
TEST_TIME 102 9517
TEST_TIME 103 9555
TEST_TIME 104 9566
TEST_TIME 105 9573
TEST_TIME 106 9608
TEST_TIME 107 9564
TEST_TIME 108 9534
TEST_TIME 109 9552
TEST_TIME 110 9664
TEST_TIME 111 9626
TEST_TIME 112 9635
TEST_TIME 113 9582
TEST_TIME 114 9551
TEST_TIME 115 9504
TEST_TIME 116 9475
TEST_TIME 117 9424
TEST_TIME 118 9386
TEST_TIME 119 9337
TEST_TIME 120 9363

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2021 19:39:49 to 01-10-2021 19: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 1572702 1572702 0 0.00 0 40489 6 0 0
doc_list_biud 1572702 1572702 0 0.00 0 23031 26 0 0
sp_add_invoice_to_stock 5532 5364 168 3.03 27 192100 17820 0 168
sp_cancel_adding_invoice 5533 5533 0 0.00 5 55143 834 0 0
sp_cancel_client_order 1774 1774 0 0.00 4 18075 365 0 0
sp_cancel_customer_reserve 547611 547611 0 0.00 1 21876 58 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 4487 86 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 8614 87 0 0
sp_cancel_supplier_invoice 6119 6107 12 0.19 2 23068 731 0 12
sp_cancel_supplier_order 387 387 0 0.00 10 955103 103933 0 0
sp_cancel_write_off 10086 10086 0 0.00 1 14403 119 0 0
sp_client_order 21718 21718 0 0.00 4 17407 341 0 0
sp_customer_reserve 486345 486345 0 0.00 0 171503 180 0 0
sp_fill_shopping_cart 33133 33133 0 0.00 1 17667 40 0 0
sp_fill_shopping_cart_clo_res 486334 486334 0 0.00 0 18251 13 0 0
sp_get_clo_for_invoice 5353 5353 0 0.00 0 22374 284 0 0
sp_kill_cost_storno 16765 16765 0 0.00 0 17935 61 0 0
sp_kill_qstorno_ret_qs2qd 1111802 1111802 0 0.00 0 23030 31 0 0
sp_kill_qty_storno 587280 587280 0 0.00 0 23031 68 0 0
sp_lock_selected_doc 21683 21683 0 0.00 0 8118 5 0 0
sp_make_cost_storno 29277 29277 0 0.00 0 40482 61 0 0
sp_multiply_rows_for_pdistr 29277 29277 0 0.00 0 12381 30 0 0
sp_multiply_rows_for_qdistr 21718 21718 0 0.00 0 14604 155 0 0
sp_pay_from_customer 7399 7399 0 0.00 4 16984 132 0 0
sp_pay_to_supplier 6096 6096 0 0.00 5 40526 151 0 0
sp_payment_common 13495 13495 0 0.00 1 16922 43 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 18065 337 0 0
sp_qd_handle_on_invoice_upd_sts 10886 10886 0 0.00 0 17414 329 0 0
sp_qd_handle_on_reserve_upd_sts 10429 10429 0 0.00 0 16829 56 0 0
sp_reserve_write_off 10429 10429 0 0.00 7 16867 189 0 0
sp_supplier_invoice 5667 5667 0 0.00 7 69104 3388 0 0
sp_supplier_order 5792 5792 0 0.00 5 37434 1223 0 0
srv_aggregate_perf_data 108 108 0 0.00 3064 105136 18674 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1427 174627 50917 0 0
srv_find_qd_qs_mism 1072698 1072698 0 0.00 0 8679 5 0 0
srv_make_invnt_saldo 636 636 0 0.00 27 27527 974 0 0
srv_make_money_saldo 858 858 0 0.00 1 9072 41 0 0
srv_recalc_idx_stat 3 3 0 0.00 114374 312145 243459 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5364 5364 0 0.00 0 12312 159 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 1892 67 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 5630 15 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 2153 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 1900 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 1141 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 413 9 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 17654 1506 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 2334 8 0 0
t$perf-norm:sp_client_order 21718 21718 0 0.00 0 6607 15 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 6909 14 0 0
t$perf-norm:sp_pay_from_customer 7399 7399 0 0.00 0 3763 9 0 0
t$perf-norm:sp_pay_to_supplier 6096 6096 0 0.00 0 6285 12 0 0
t$perf-norm:sp_reserve_write_off 10429 10429 0 0.00 0 8361 10 0 0
t$perf-norm:sp_supplier_invoice 5667 5667 0 0.00 0 1801 13 0 0
t$perf-norm:sp_supplier_order 5792 5792 0 0.00 0 4537 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 144 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 11 1 0 0
t$perf-norm:srv_make_invnt_saldo 636 636 0 0.00 0 681 17 0 0
t$perf-norm:srv_make_money_saldo 858 858 0 0.00 0 753 15 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 111 39 0 0
v_add_invoice_to_stock 11036 10700 336 3.04 0 3457 19 0 336
v_all_customers 39598 39598 0 0.00 0 67 1 0 0
v_all_suppliers 31096 31096 0 0.00 0 193 1 0 0
v_all_wares 43388 43388 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 7187 15 0 0
v_cancel_client_order 3548 3548 0 0.00 0 7532 6 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 3293 9 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 6977 9 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1957 6 0 0
v_cancel_supplier_invoice 898 874 24 2.67 0 382 14 0 24
v_cancel_supplier_order 774 774 0 0.00 0 4748 10 0 0
v_cancel_write_off 2024 2024 0 0.00 0 5773 26 0 0
v_max_id_clo_ord 5785 5785 0 0.00 0 262 1 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 3443 20 0 0
v_max_id_ord_sup 5654 5654 0 0.00 0 2722 5 0 0
v_max_non_paid_invoice 1987 1987 0 0.00 0 920 3 0 0
v_max_non_paid_realizn 3936 3936 0 0.00 0 2131 4 0 0
v_min_id_clo_ord 5785 5785 0 0.00 0 4091 6 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 7447 12 0 0
v_min_id_ord_sup 5654 5654 0 0.00 0 4535 5 0 0
v_min_non_paid_invoice 6096 6096 0 0.00 0 15011 27 0 0
v_min_non_paid_realizn 7399 7399 0 0.00 0 16876 31 0 0
v_our_firm 10716 10716 0 0.00 0 26 1 0 0
v_reserve_write_off 20850 20850 0 0.00 0 11250 13 0 0
x_lock_depdocs_on_canc_invoice 11203 11203 0 0.00 0 27522 130 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 9929 352 0 0
x_make_qstorno_1000_1200 5792 5792 0 0.00 1 37390 1132 0 0
x_make_qstorno_1200_2000 5667 5667 0 0.00 3 67076 3254 0 0
x_make_qstorno_2100_3300 486345 486345 0 0.00 0 171501 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-10-2021 19:39:53 to 01-10-2021 19: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
17:07:55 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:56 19226 8192000000 10353001408 10670014464 1406059008 14.649 100 86 172 0 1440368688 5893440 28416240
17:10:23 15965 8192000000 10444276768 10773213184 1486617136 15.359 100 88 176 1 1522706032 7902480 28186416
17:11:44 4697 8192000000 10491332464 10831290368 1547107344 15.885 100 86 172 0 1583988400 9734976 27146080
17:12:54 13658 8192000000 10559659168 10909372416 1610585728 16.430 100 80 160 0 1645687152 10747488 24353936
17:14:12 11842 8192000000 10613639712 10981478400 1678982912 17.009 100 81 160 0 1714210448 11647680 23579856
17:15:28 5793 8192000000 10688042800 11056803840 1747347616 17.580 100 91 182 1 1787565120 14386928 25830576
17:16:36 3714 8192000000 10739378896 11119546368 1794217408 17.966 100 79 158 0 1827673632 12097520 21358704
17:17:42 1982 8192000000 10786344976 11165011968 1838516976 18.329 100 89 178 1 1877879856 13598448 25764432
17:18:46 1290 8192000000 10818566864 11204100096 1872469056 18.604 100 95 189 1 1913185136 13522960 27193120
17:19:48 23440 8192000000 10834269920 11233247232 1893140096 18.771 100 63 125 2 1917056144 5145360 18770688
17:21:12 8268 8192000000 10812581328 11242254336 1901388736 18.837 100 22 44 1 1913902800 6798768 5715296
17:22:24 14311 8192000000 10815965376 11251822592 1907683296 18.888 100 21 42 2 1924290336 10938688 5668352
17:23:39 56162 8192000000 10848582656 11263926272 1913975120 18.939 100 36 72 0 1930699712 5208608 11515984
17:25:37 8383 8192000000 10826709952 11262136320 1918682096 18.976 100 28 55 2 1932454272 5997776 7774400
17:26:45 35338 8192000000 10831232656 11269976064 1901561328 18.839 99 26 49 2 1913909552 5487440 6860784
17:28:21 11646 8192000000 10768144944 11202011136 1795709632 17.979 95 18 36 0 1806296944 5364016 5223296
17:29:33 9210 8192000000 10747551792 11176669184 1801464608 18.026 97 21 42 0 1812727744 5610736 5652400
17:30:44 39248 8192000000 10710624816 11141238784 1761523120 17.697 96 29 59 0 1779601328 9483360 8594848
17:32:24 9023 8192000000 10746027824 11160276992 1791500016 17.944 98 21 43 1 1803382304 5700960 6181328
17:33:34 11704 8192000000 10754566704 11175583744 1838024320 18.325 100 20 40 0 1849540288 5197104 6318864
17:34:49 14269 8192000000 10764941712 11190407168 1842089488 18.358 99 31 62 1 1861186912 10332320 8765104
17:36:06 26202 8192000000 10773540256 11204673536 1864050160 18.536 100 17 34 0 1874155456 5486768 4618528
17:37:39 6340 8192000000 10812231552 11244470272 1901826672 18.841 100 20 40 1 1912304128 4841104 5636352
17:38:50 8596 8192000000 10854062336 11275755520 1926890160 19.042 100 55 108 2 1951248192 8156848 16201184
17:40:03 37187 8192000000 10875810720 11306614784 1948098976 19.211 100 42 80 2 1970410208 10066352 12244880
17:41:54 57803 8192000000 10904626048 11340091392 1966217632 19.355 100 46 90 1 1994871392 15236928 13416832
17:43:56 25483 8192000000 10904644544 11344773120 1969082592 19.378 100 40 79 1 1997462704 16829488 11550624
17:45:29 14385 8192000000 10929558432 11366277120 1982144096 19.482 100 54 108 2 2019366608 19914352 17308160
17:46:50 5023 8192000000 10932366496 11363270656 1972918816 19.409 99 57 113 2 2009253264 19918448 16416000
17:47:58 17508 8192000000 10955029280 11383402496 1986339264 19.515 99 65 131 0 2031390608 25433152 19618192
17:49:21 2914 8192000000 10941488160 11362504704 1978023888 19.449 100 76 150 0 2030097760 28898176 23175696
17:50:26 2219 8192000000 10960520608 11382329344 1992584976 19.564 100 78 155 0 2047051232 30099488 24366768
17:51:35 7659 8192000000 10954227664 11383676928 1974355632 19.420 99 71 142 0 2028035616 32750944 20929040
17:52:47 2347 8192000000 10957770720 11372486656 1976625664 19.438 100 80 158 0 2038787344 37930672 24231008
17:53:52 1877 8192000000 10982704192 11389984768 1984169072 19.498 100 87 175 1 2054242064 42626720 27446272
17:54:58 6014 8192000000 10949458880 11378704384 1963467776 19.334 99 59 120 1 2022572912 41350224 17754912
17:56:10 10171 8192000000 10925244880 11345457152 1937709168 19.128 99 68 136 2 2006244896 47420016 21115712
17:57:28 1920 8192000000 10975553952 11387047936 1970927520 19.393 100 90 178 1 2051619904 52988816 27703568
17:58:36 1426 8192000000 10978977376 11395153920 1980297424 19.467 100 86 169 2 2065650240 59137376 26215440
17:59:42 7412 8192000000 10944356656 11377602560 1963865792 19.337 100 44 86 2 2028239232 50248224 14125216
18:01:00 26463 8192000000 10925677504 11368665088 1946509408 19.199 100 37 73 0 2010078736 51523264 12046064
18:02:39 24076 8192000000 10913057216 11372077056 1946193648 19.196 100 15 30 1 1995256976 43563952 5499376
18:04:14 59317 8192000000 10833433168 11287031808 1802148880 18.032 93 11 23 1 1830149808 24411040 3589888
18:06:14 76721 8192000000 10875272752 11332349952 1895069984 18.787 98 8 16 0 1910796752 13655792 2070976
18:08:45 37247 8192000000 10917206912 11364564992 1972574384 19.406 100 40 80 2 1999371104 14900416 11896304
18:10:42 118719 8192000000 10906923008 11354066944 1917198896 18.964 97 28 56 1 1938372400 13849680 7323824
18:13:49 88418 8192000000 10894465840 11354710016 1943839360 19.177 98 25 50 0 1959292512 7686992 7766160
18:16:19 5718 8192000000 10952876672 11383345152 1999895104 19.622 100 69 138 3 2025607008 6622240 19089664
18:17:27 174627 8192000000 10928309200 11393323008 1911513552 18.919 95 16 30 1 1932902736 16232336 5156848
18:21:35 54841 8192000000 10923059168 11410161664 1999304384 19.617 100 17 34 2 2023555248 19105712 5145152
18:24:16 52618 8192000000 10931542848 11405500416 1982795776 19.487 99 13 26 0 2012428112 25435520 4196816
18:26:10 95910 8192000000 10914236112 11388907520 1944554400 19.183 97 19 38 3 1974066608 23439408 6072800
18:28:46 85574 8192000000 10910648784 11386261504 1925385360 19.030 97 15 30 0 1949995840 20035088 4575392
18:31:19 8867 8192000000 10895136336 11344535552 1962789488 19.328 99 61 122 1 1990077968 10170112 17118368
18:32:29 24350 8192000000 10918283456 11355652096 1922454672 19.007 97 43 84 0 1946059968 11441360 12163936
18:33:55 67829 8192000000 10903905072 11385405440 1954167600 19.260 99 11 22 1 1973670240 16221760 3280880
18:36:04 111524 8192000000 10878868384 11329232896 1737233712 17.496 88 16 32 1 1753563408 11893296 4436400
18:38:57 70456 8192000000 10883363776 11341381632 1909850480 18.905 98 30 58 3 1932394784 14089872 8454432
18:41:09 59774 8192000000 10886736320 11356966912 1938050368 19.131 99 10 20 0 1957585200 16521376 3013456
18:43:11 61582 8192000000 10865344896 11301011456 1853967328 18.454 95 31 62 2 1877917680 14816992 9133360
18:45:17 60276 8192000000 10854554736 11328421888 1909403408 18.902 99 13 26 1 1925727616 12766208 3558000
18:47:27 49940 8192000000 10860033248 11332067328 1908547360 18.895 98 14 28 1 1928234448 15651472 4035616
18:49:28 60958 8192000000 10875904688 11353735168 1935262848 19.109 99 20 39 0 1954025648 13124960 5637840
18:51:37 60309 8192000000 10855699840 11336462336 1921452736 18.998 99 6 12 0 1934004032 10968272 1583024
18:53:39 94954 8192000000 10847016272 11332083712 1869442480 18.580 96 18 36 0 1884938992 10309360 5187152
18:56:22 46720 8192000000 10879184320 11344048128 1921547648 18.999 98 19 38 2 1936927104 9975440 5404016
18:58:13 97709 8192000000 10885227632 11347628032 1889468672 18.741 96 17 34 0 1914918336 20388592 5061072
19:00:54 29190 8192000000 10901271856 11362312192 1962675840 19.327 100 23 46 0 1981569808 12646544 6247424
19:02:27 54216 8192000000 10902408160 11354808320 1891410560 18.757 96 26 51 1 1911722720 13286880 7025280
19:04:57 59924 8192000000 10873798624 11338096640 1927938880 19.050 98 19 38 1 1946694336 13054432 5701024
19:07:16 93854 8192000000 10920245056 11379814400 1930543792 19.071 97 17 34 0 1944350896 9711248 4095856
19:09:52 86364 8192000000 10914524064 11381538816 1974500560 19.421 99 20 40 1 1985646448 5787216 5358672
19:12:30 97334 8192000000 10901176576 11374923776 1965006160 19.346 98 7 14 0 1977949600 10766272 2177168
19:15:09 63537 8192000000 10926941600 11406958592 1980949440 19.472 99 18 36 0 1999708560 13379440 5379680
19:17:22 68108 8192000000 10929507152 11403829248 1986175456 19.514 99 13 27 1 2009649632 19942176 3532000
19:19:38 41656 8192000000 10911312080 11385679872 1972667776 19.407 99 34 66 1 1995907424 12293296 10946352
19:21:26 88296 8192000000 10879583344 11352727552 1898701376 18.816 96 20 38 1 1915916672 11526944 5688352
19:23:59 37853 8192000000 10912257584 11355963392 1941542912 19.159 99 26 52 1 1956739936 8019504 7177520
19:25:40 36864 8192000000 10921743968 11370926080 1938465424 19.135 98 40 79 0 1966615728 17074464 11075840
19:27:19 93170 8192000000 10876170592 11348615168 1841465344 18.353 94 13 26 1 1868274976 23442320 3367312
19:29:53 106884 8192000000 10872449392 11349737472 1943516320 19.175 100 30 60 0 1968852112 17000304 8335488
19:32:48 47971 8192000000 10858397216 11341475840 1934266112 19.101 99 17 35 0 1946856336 7769664 4820560
19:34:41 63268 8192000000 10854517280 11333992448 1897150384 18.803 97 7 14 0 1907425072 8106048 2168640
19:37:05 51962 8192000000 9758020704 10125185024 891791424 9.817 47 9 18 0 904927136 10488048 2647664

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-10-2021 19:39:59 to 01-10-2021 19:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 01-10-2021 19:40:15 to 01-10-2021 19: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          244997
MON$OLDEST_ACTIVE               246112
MON$OLDEST_SNAPSHOT             246112
MON$NEXT_TRANSACTION            246112
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                       2042464
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33506 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33506 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33506 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33506 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Oct  1 19:38:10 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Oct  1 19:38:12 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Fri Oct  1 19:38:12 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Fri Oct  1 19:38:14 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
--- end of diff output ---

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

01.10.21 19: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
2021-10-01 19:40:20 No severe PSQL-related problems occured
01.10.21 19:40:21. End of report.