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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-15 16:38:11
DTS_END                         2022-04-15 16:38:11.8850
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/39676
TRN_ID                          245232
ATT_ID                          616
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: 8894.38 22164 1067326
customer order: creation 176.59 350 21191
customer order: refuse 15.14 347 1817
order to supplier: creation 45.90 1260 5508
order to supplier: removal 3.45 99821 414
invoice (draft): creation 46.22 3453 5546
invoice (draft): removal 48.22 812 5786
invoice accept: apply 42.69 17908 5123
invoice accept: cancel 43.27 866 5192
customer reserve: creation 3870.72 180 464486
customer reserve: removal 4310.77 65 517293
realization accept: apply 86.01 174 10321
realization accept: cancel 72.40 113 8688
payment from customer: creation 60.67 119 7280
payment from customer: removal 5.64 95 677
payment to supplier: creation 50.64 133 6077
payment to supplier: removal 3.55 77 426
service: total inventory turnovers 4.82 1133 578
service: total monetary turnovers 6.82 20 818
service: aggregate perf. data 0.84 21653 101
service: refresh index statistics 0.03 294697 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-04-2022 16:39:56 to 15-04-2022 16:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8859
TEST_TIME 2 7141
TEST_TIME 3 6560
TEST_TIME 4 6579
TEST_TIME 5 6353
TEST_TIME 6 6486
TEST_TIME 7 6721
TEST_TIME 8 6450
TEST_TIME 9 6474
TEST_TIME 10 6161
TEST_TIME 11 6042
TEST_TIME 12 6049
TEST_TIME 13 6293
TEST_TIME 14 6470
TEST_TIME 15 6540
TEST_TIME 16 6968
TEST_TIME 17 7292
TEST_TIME 18 7575
TEST_TIME 19 7824
TEST_TIME 20 8109
TEST_TIME 21 8559
TEST_TIME 22 8859
TEST_TIME 23 9200
TEST_TIME 24 9328
TEST_TIME 25 9277
TEST_TIME 26 9408
TEST_TIME 27 9786
TEST_TIME 28 9983
TEST_TIME 29 10257
TEST_TIME 30 10263
TEST_TIME 31 10348
TEST_TIME 32 10241
TEST_TIME 33 10012
TEST_TIME 34 9950
TEST_TIME 35 9831
TEST_TIME 36 9678
TEST_TIME 37 9585
TEST_TIME 38 9550
TEST_TIME 39 9441
TEST_TIME 40 9346
TEST_TIME 41 9263
TEST_TIME 42 9212
TEST_TIME 43 9088
TEST_TIME 44 9007
TEST_TIME 45 8952
TEST_TIME 46 9050
TEST_TIME 47 9153
TEST_TIME 48 9077
TEST_TIME 49 9170
TEST_TIME 50 9068
TEST_TIME 51 8957
TEST_TIME 52 8910
TEST_TIME 53 8807
TEST_TIME 54 8752
TEST_TIME 55 8750
TEST_TIME 56 8715
TEST_TIME 57 8689
TEST_TIME 58 8833
TEST_TIME 59 8865
TEST_TIME 60 8885
TEST_TIME 61 8898
TEST_TIME 62 8919
TEST_TIME 63 8954
TEST_TIME 64 9088
TEST_TIME 65 9064
TEST_TIME 66 9071
TEST_TIME 67 9114
TEST_TIME 68 9231
TEST_TIME 69 9289
TEST_TIME 70 9222
TEST_TIME 71 9144
TEST_TIME 72 9112
TEST_TIME 73 9105
TEST_TIME 74 9062
TEST_TIME 75 9047
TEST_TIME 76 9092
TEST_TIME 77 9067
TEST_TIME 78 9022
TEST_TIME 79 8965
TEST_TIME 80 8887
TEST_TIME 81 8880
TEST_TIME 82 8930
TEST_TIME 83 8961
TEST_TIME 84 8997
TEST_TIME 85 8982
TEST_TIME 86 9000
TEST_TIME 87 9056
TEST_TIME 88 9090
TEST_TIME 89 9048
TEST_TIME 90 9124
TEST_TIME 91 9140
TEST_TIME 92 9176
TEST_TIME 93 9168
TEST_TIME 94 9182
TEST_TIME 95 9116
TEST_TIME 96 9116
TEST_TIME 97 9063
TEST_TIME 98 9048
TEST_TIME 99 8999
TEST_TIME 100 9016
TEST_TIME 101 8984
TEST_TIME 102 9054
TEST_TIME 103 9030
TEST_TIME 104 8991
TEST_TIME 105 9059
TEST_TIME 106 9090
TEST_TIME 107 9070
TEST_TIME 108 9063
TEST_TIME 109 9067
TEST_TIME 110 9106
TEST_TIME 111 9151
TEST_TIME 112 9123
TEST_TIME 113 9127
TEST_TIME 114 9070
TEST_TIME 115 9051
TEST_TIME 116 9013
TEST_TIME 117 9024
TEST_TIME 118 8999
TEST_TIME 119 8965
TEST_TIME 120 8900

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-04-2022 16:40:00 to 15-04-2022 16:40:03.

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 1496031 1496031 0 0.00 0 18610 6 0 0
doc_list_biud 1496030 1496030 0 0.00 0 25603 28 0 0
sp_add_invoice_to_stock 5252 5123 129 2.45 21 171047 17909 0 129
sp_cancel_adding_invoice 5192 5192 0 0.00 4 74770 867 0 0
sp_cancel_client_order 1817 1817 0 0.00 4 13165 347 0 0
sp_cancel_customer_reserve 517293 517293 0 0.00 1 25605 65 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 2 7968 96 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 2 3640 77 0 0
sp_cancel_supplier_invoice 5796 5786 10 0.17 1 20594 812 0 10
sp_cancel_supplier_order 414 414 0 0.00 5 851289 99822 0 0
sp_cancel_write_off 8688 8688 0 0.00 1 11664 113 0 0
sp_client_order 21191 21191 0 0.00 3 14626 351 0 0
sp_customer_reserve 464486 464486 0 0.00 0 44771 181 0 0
sp_fill_shopping_cart 32241 32241 0 0.00 1 26353 44 0 0
sp_fill_shopping_cart_clo_res 464480 464480 0 0.00 0 20279 15 0 0
sp_get_clo_for_invoice 5116 5116 0 0.00 0 30350 305 0 0
sp_kill_cost_storno 14983 14983 0 0.00 0 7678 59 0 0
sp_kill_qstorno_ret_qs2qd 1049474 1049474 0 0.00 0 21907 35 0 0
sp_kill_qty_storno 554627 554627 0 0.00 0 25602 75 0 0
sp_lock_selected_doc 21387 21387 0 0.00 0 8738 6 0 0
sp_make_cost_storno 28794 28794 0 0.00 0 18607 54 0 0
sp_multiply_rows_for_pdistr 28793 28793 0 0.00 0 10693 28 0 0
sp_multiply_rows_for_qdistr 21191 21191 0 0.00 0 9867 155 0 0
sp_pay_from_customer 7280 7280 0 0.00 4 10733 120 0 0
sp_pay_to_supplier 6077 6077 0 0.00 4 18616 134 0 0
sp_payment_common 13356 13356 0 0.00 1 18017 33 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 13152 320 0 0
sp_qd_handle_on_invoice_upd_sts 10308 10308 0 0.00 0 20977 328 0 0
sp_qd_handle_on_reserve_upd_sts 10321 10321 0 0.00 0 14401 55 0 0
sp_reserve_write_off 10321 10321 0 0.00 6 15921 174 0 0
sp_supplier_invoice 5546 5546 0 0.00 7 72500 3453 0 0
sp_supplier_order 5508 5508 0 0.00 5 27719 1260 0 0
srv_aggregate_perf_data 101 101 0 0.00 2866 495555 21654 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1393 109301 45120 0 0
srv_find_qd_qs_mism 1018103 1018103 0 0.00 0 11668 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 29 62251 1134 0 0
srv_make_money_saldo 818 818 0 0.00 1 1039 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 121442 384605 294697 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5124 5124 0 0.00 0 13922 160 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 3811 81 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 2560 12 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 1974 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 4354 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 934 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 456 456 0 0.00 0 1229 9 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 23753 1618 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 4552 20 0 0
t$perf-norm:sp_client_order 21191 21191 0 0.00 0 11325 16 0 0
t$perf-norm:sp_customer_reserve 1054 1054 0 0.00 0 516 3 0 0
t$perf-norm:sp_pay_from_customer 7280 7280 0 0.00 0 7671 11 0 0
t$perf-norm:sp_pay_to_supplier 6077 6077 0 0.00 0 6092 12 0 0
t$perf-norm:sp_reserve_write_off 10321 10321 0 0.00 0 5045 10 0 0
t$perf-norm:sp_supplier_invoice 5546 5546 0 0.00 0 5627 15 0 0
t$perf-norm:sp_supplier_order 5508 5508 0 0.00 0 4596 15 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 2298 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 719 12 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 4087 39 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 1830 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 14 5 0 0
v_add_invoice_to_stock 10488 10230 258 2.45 0 6245 18 0 258
v_all_customers 38346 38346 0 0.00 0 15 0 0 0
v_all_suppliers 30654 30654 0 0.00 0 24 1 0 0
v_all_wares 42382 42382 0 0.00 0 208 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 3990 10 0 0
v_cancel_client_order 3634 3634 0 0.00 0 2634 5 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 7948 16 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 1651 5 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 3616 9 0 0
v_cancel_supplier_invoice 932 912 20 2.14 0 631 16 0 20
v_cancel_supplier_order 828 828 0 0.00 0 6129 12 0 0
v_cancel_write_off 2068 2068 0 0.00 0 2528 15 0 0
v_max_id_clo_ord 5508 5508 0 0.00 0 243 1 0 0
v_max_id_clo_res 1054 1054 0 0.00 0 2641 13 0 0
v_max_id_ord_sup 5542 5542 0 0.00 0 8661 9 0 0
v_max_non_paid_invoice 1799 1799 0 0.00 0 7961 7 0 0
v_max_non_paid_realizn 3940 3940 0 0.00 0 2162 5 0 0
v_min_id_clo_ord 5508 5508 0 0.00 0 4505 4 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 2754 7 0 0
v_min_id_ord_sup 5542 5542 0 0.00 0 8856 18 0 0
v_min_non_paid_invoice 6076 6076 0 0.00 0 18014 23 0 0
v_min_non_paid_realizn 7280 7280 0 0.00 0 8709 25 0 0
v_our_firm 10716 10716 0 0.00 0 14 1 0 0
v_reserve_write_off 20642 20642 0 0.00 0 5811 8 0 0
x_lock_depdocs_on_canc_invoice 10522 10522 0 0.00 0 14704 111 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 22470 414 0 0
x_make_qstorno_1000_1200 5508 5508 0 0.00 2 27708 1184 0 0
x_make_qstorno_1200_2000 5546 5546 0 0.00 3 72491 3279 0 0
x_make_qstorno_2100_3300 464486 464486 0 0.00 0 29558 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-04-2022 16:40:04 to 15-04-2022 16:40:08.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:15 14 8192000000 8806667120 8820592640 11734608 0.143 4 3 3 1 11889632 71104 83920
14:09:16 27162 8192000000 9903548432 10216321024 990240800 10.784 100 86 173 0 1019567264 6270304 23056160
14:10:53 18981 8192000000 9950777936 10269839360 1031390736 11.182 100 91 182 0 1063077216 8242896 23443584
14:12:17 17861 8192000000 9978142336 10307653632 1059198224 11.449 100 84 168 0 1089511968 9274000 21039744
14:13:39 17600 8192000000 10038841280 10384281600 1124089520 12.066 100 82 165 1 1154566720 10504512 19972688
14:15:00 9390 8192000000 10088212272 10446970880 1173853088 12.533 100 75 150 0 1203708336 12826480 17028768
14:16:12 6405 8192000000 10138075824 10509275136 1226662848 13.023 100 80 160 0 1258236352 13035552 18537952
14:17:19 10313 8192000000 10166398368 10548826112 1260656368 13.336 100 69 137 2 1288352368 12998592 14697408
14:18:32 1948 8192000000 10216752208 10596978688 1295430448 13.654 100 92 184 1 1328768464 13280944 20057072
14:19:35 2940 8192000000 10200185584 10600009728 1303252992 13.725 100 40 80 0 1320227712 8201808 8772912
14:20:40 6295 8192000000 10202733984 10613293056 1312745728 13.811 100 41 82 1 1331365296 9384320 9235248
14:21:47 16054 8192000000 10208515504 10624700416 1322056448 13.895 100 26 52 0 1334831344 5786560 6988336
14:23:05 32448 8192000000 10224331392 10639417344 1325853600 13.930 100 37 74 1 1340857184 6753648 8249936
14:24:38 5055 8192000000 10229889184 10645434368 1332238640 13.987 100 36 72 0 1347877680 8009488 7629552
14:25:43 7196 8192000000 10233862080 10651435008 1335324208 14.015 100 25 46 3 1346446000 5825888 5295904
14:26:51 41383 8192000000 10229300304 10649944064 1323346624 13.907 99 41 80 1 1339335280 5897264 10091392
14:28:35 6877 8192000000 10189460576 10604961792 1290233296 13.606 99 26 52 2 1302267456 6091840 5942320
14:29:44 4867 8192000000 10154903328 10555625472 1260645376 13.336 99 28 56 2 1273085296 6172736 6267184
14:30:52 63950 8192000000 10142159680 10548977664 1187207392 12.657 93 23 46 1 1197301584 4541488 5552704
14:32:58 3904 8192000000 10174886176 10565926912 1256241552 13.296 99 49 92 2 1276172720 7245808 12685360
14:34:02 8939 8192000000 10174351776 10586718208 1285090640 13.559 100 22 43 0 1295801536 5367072 5343824
14:35:13 76860 8192000000 10191808624 10607656960 1300245312 13.697 100 33 66 0 1313289072 4991232 8052528
14:37:32 5577 8192000000 10204828944 10618494976 1309814160 13.784 100 20 40 1 1319922544 5516320 4592064
14:38:43 6004 8192000000 10246799136 10655272960 1340170848 14.059 100 53 107 2 1360072944 6881568 13020528
14:39:54 8866 8192000000 10264871024 10675191808 1351263360 14.159 100 51 99 2 1372356128 10187904 10904864
14:41:05 51606 8192000000 10273389616 10702835712 1369112144 14.319 100 30 60 2 1386316896 9930128 7274624
14:43:51 9297 8192000000 10309685344 10729861120 1391919504 14.523 100 55 110 1 1418269472 13925552 12424416
14:45:04 4636 8192000000 10311777216 10732040192 1389773872 14.504 100 65 129 1 1421918112 16677488 15466752
14:46:17 25521 8192000000 10312846480 10738257920 1386999504 14.479 99 49 98 2 1415391040 17155888 11235648
14:47:49 18934 8192000000 10320107760 10751422464 1395421168 14.554 99 31 62 1 1420049392 16953504 7674720
14:49:10 14167 8192000000 10341368592 10764791808 1413611680 14.716 100 53 106 1 1449301840 21718352 13971808
14:50:27 8944 8192000000 10338218496 10759942144 1406456752 14.652 100 53 105 3 1448255344 28069024 13729568
14:51:41 3283 8192000000 10366903760 10788405248 1428280688 14.846 100 71 140 1 1477203856 30782608 18140560
14:52:48 1954 8192000000 10360530864 10770980864 1409566048 14.680 99 80 157 1 1462368272 33167376 19634848
14:53:53 1393 8192000000 10370968944 10786902016 1416536240 14.742 100 74 147 0 1475616176 40239856 18840080
14:55:05 3220 8192000000 10338195792 10741096448 1384221568 14.454 99 87 170 1 1442142176 36700592 21220016
14:56:15 2430 8192000000 10345431888 10756370432 1386189632 14.472 100 74 147 3 1447527984 42464128 18874224
14:57:29 4393 8192000000 10328262560 10740420608 1362299744 14.258 99 63 126 1 1421677376 42841840 16535792
14:58:42 13964 8192000000 10328712896 10758664192 1372718624 14.351 99 43 85 0 1426302624 41840944 11743056
15:00:04 22017 8192000000 10303866784 10735071232 1356716112 14.208 98 27 54 0 1402688752 38188224 7784416
15:01:35 9955 8192000000 10298473984 10730106880 1359844176 14.236 99 30 60 0 1401192992 32758720 8590096
15:02:56 15222 8192000000 10286112784 10725060608 1350895280 14.156 99 28 56 1 1385771984 26934784 7941920
15:04:19 7186 8192000000 10303118480 10734751744 1364567008 14.278 100 29 58 1 1408043728 35304368 8172352
15:05:28 29444 8192000000 10295516416 10738978816 1362335344 14.258 100 16 32 2 1400523024 33420864 4766816
15:07:00 22002 8192000000 10271470528 10722353152 1348495648 14.134 99 34 68 0 1377122048 19803008 8823392
15:08:33 12824 8192000000 10280163168 10714357760 1348964608 14.138 99 34 64 1 1372924576 16345072 7614896
15:09:48 42341 8192000000 10244387168 10698612736 1323664336 13.910 98 8 16 0 1336679664 11485872 1529456
15:11:31 66506 8192000000 10277072528 10724626432 1365011536 14.282 100 27 53 1 1385796096 14947728 5836832
15:13:37 81252 8192000000 10280930912 10726473728 1374647792 14.369 100 25 51 1 1390090144 9311760 6130592
15:16:00 101282 8192000000 10272387232 10728923136 1360078736 14.238 99 21 42 0 1376522944 11541696 4902512
15:18:44 38536 8192000000 10278867264 10717274112 1359641664 14.234 99 26 50 4 1372500144 7069152 5789328
15:20:25 74099 8192000000 10284458320 10735296512 1379361680 14.411 100 14 28 0 1392676480 9994096 3320704
15:22:49 44421 8192000000 10308400064 10755227648 1383980336 14.452 99 16 32 0 1406086176 17833248 4272592
15:24:34 40178 8192000000 10304400944 10768990208 1385183760 14.463 99 22 44 2 1413475792 22593840 5698192
15:26:26 38247 8192000000 10290256864 10750865408 1390549520 14.511 100 14 28 1 1411407968 16651248 4207200
15:28:07 73141 8192000000 10279079952 10734948352 1383308208 14.446 100 13 26 0 1392368160 6148816 2911136
15:30:25 78264 8192000000 10273591888 10735693824 1380918032 14.425 99 20 39 1 1392323712 7419168 3986512
15:32:53 40015 8192000000 10274170176 10741088256 1363500832 14.269 98 34 68 0 1381001040 8134992 9365216
15:34:35 50030 8192000000 10297758544 10757926912 1391245728 14.517 100 19 38 0 1406849936 11284928 4319280
15:36:33 98392 8192000000 10268753360 10730717184 1328590592 13.954 96 10 20 0 1342035936 11557008 1888336
15:39:13 49563 8192000000 10266516224 10723000320 1334241200 14.005 97 16 32 0 1347765840 10022944 3501696
15:41:03 94310 8192000000 10286059344 10744029184 1337993536 14.039 96 13 26 1 1352922000 12141248 2787216
15:43:39 14873 8192000000 10289922048 10721316864 1343843696 14.092 97 50 100 0 1363541552 8348800 11349056
15:44:59 35734 8192000000 10285479264 10722029568 1326278512 13.934 97 43 84 0 1350020320 14193328 9548480
15:46:36 109301 8192000000 10275044032 10718093312 1335552224 14.017 97 7 14 0 1355326368 18004560 1769584
15:49:28 78221 8192000000 10280986272 10716405760 1348565120 14.135 98 35 70 1 1365718384 9355520 7797744
15:51:47 53511 8192000000 10284285040 10727354368 1348172384 14.131 98 41 83 0 1368715856 10976144 9567328
15:54:14 86502 8192000000 10243886000 10701688832 1309784592 13.784 96 11 22 0 1320475984 8398112 2293280
15:56:48 79333 8192000000 10255768144 10706427904 1351582256 14.162 100 15 30 0 1370181104 15064464 3534384
15:59:47 48724 8192000000 10253141744 10712899584 1325036912 13.922 97 11 22 0 1345834336 17926464 2870960
16:01:40 69060 8192000000 10262303888 10718932992 1327429328 13.944 97 8 16 0 1338539392 9389504 1720560
16:03:58 40778 8192000000 10294103056 10746580992 1381131392 14.427 100 26 52 1 1401494016 14704960 5657664
16:05:56 53679 8192000000 10274340992 10720518144 1342320704 14.078 97 17 33 0 1359841472 14022928 3497840
16:07:56 53275 8192000000 10264294816 10719457280 1334708112 14.010 97 31 62 2 1354102448 12418128 6976208
16:09:57 33421 8192000000 10294007168 10737033216 1378343600 14.402 100 28 53 1 1397625136 12936096 6345440
16:11:33 55546 8192000000 10306698864 10758778880 1376903424 14.389 99 9 18 1 1394664448 15958160 1802864
16:13:33 70230 8192000000 10288761792 10747613184 1365553824 14.287 98 30 58 3 1385934464 13522496 6858144
16:15:44 69026 8192000000 10288608560 10758836224 1370316224 14.330 98 9 18 0 1386423104 14345296 1761584
16:17:53 51969 8192000000 10293247424 10755592192 1377869840 14.397 99 18 36 1 1399351984 17360944 4121200
16:19:46 65495 8192000000 10263891488 10729406464 1305375616 13.744 94 11 22 0 1323639072 15457424 2806032
16:21:52 59264 8192000000 10301164576 10750332928 1362761920 14.262 98 25 50 0 1382591744 14016752 5813072
16:23:51 105345 8192000000 10279169712 10728660992 1324873952 13.921 96 14 28 0 1338980752 11348048 2758752
16:26:38 30358 8192000000 10304745936 10748276736 1371204736 14.338 99 22 44 0 1387201360 11380288 4616336
16:28:09 60052 8192000000 10295771168 10740981760 1354682096 14.190 98 12 24 0 1370987136 13022208 3282832
16:30:13 75416 8192000000 10296868288 10740625408 1346204944 14.113 97 26 52 0 1362408768 9964544 6239280
16:32:29 102668 8192000000 10274024320 10723655680 1355018992 14.193 98 33 66 0 1370186448 8211344 6956112
16:35:26 78018 8192000000 10249674192 10709880832 1335153584 14.014 98 16 32 1 1346966656 8477088 3335984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-04-2022 16:40:10 to 15-04-2022 16:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

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

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          245231
MON$OLDEST_ACTIVE               246619
MON$OLDEST_SNAPSHOT             246619
MON$NEXT_TRANSACTION            246619
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                       2008896
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:442: 
:443: mscs-test-161.budzdorov.ru    Fri Apr 15 16:38:18 2022
:444:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:445: 
:446: 
:447: mscs-test-161.budzdorov.ru    Fri Apr 15 16:38:19 2022
:448:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:449: 
:450: 
:451: mscs-test-161.budzdorov.ru    Fri Apr 15 16:38:27 2022
:452:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:453: 
--- end of diff output ---

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

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