Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.06.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 16337720 kB
MemAvailable 31261488 kB
Buffers 0 kB
Cached 15411452 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.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-24 01:38:20
DTS_END                         2022-06-24 01:38:20.2160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55512
TRN_ID                          241280
ATT_ID                          707
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: 8986.40 24348 1078368
customer order: creation 168.72 373 20246
customer order: refuse 14.73 376 1767
order to supplier: creation 45.92 1178 5510
order to supplier: removal 3.43 92122 412
invoice (draft): creation 44.92 3609 5390
invoice (draft): removal 50.03 756 6004
invoice accept: apply 42.32 17600 5078
invoice accept: cancel 45.38 824 5446
customer reserve: creation 3833.68 176 460042
customer reserve: removal 4451.26 56 534151
realization accept: apply 82.59 196 9911
realization accept: cancel 73.02 120 8762
payment from customer: creation 59.93 128 7191
payment from customer: removal 5.77 89 692
payment to supplier: creation 49.41 127 5929
payment to supplier: removal 3.55 97 426
service: total inventory turnovers 4.71 1220 565
service: total monetary turnovers 6.16 32 739
service: aggregate perf. data 0.86 17145 103
service: refresh index statistics 0.03 350743 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-06-2022 01:40:31 to 24-06-2022 01:40:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3493
TEST_TIME 2 3945
TEST_TIME 3 4628
TEST_TIME 4 4164
TEST_TIME 5 4252
TEST_TIME 6 4542
TEST_TIME 7 4490
TEST_TIME 8 4574
TEST_TIME 9 4996
TEST_TIME 10 5584
TEST_TIME 11 5909
TEST_TIME 12 6455
TEST_TIME 13 6873
TEST_TIME 14 7147
TEST_TIME 15 7538
TEST_TIME 16 7971
TEST_TIME 17 8278
TEST_TIME 18 8379
TEST_TIME 19 9038
TEST_TIME 20 9324
TEST_TIME 21 9640
TEST_TIME 22 9707
TEST_TIME 23 9815
TEST_TIME 24 10249
TEST_TIME 25 10330
TEST_TIME 26 10108
TEST_TIME 27 9911
TEST_TIME 28 9998
TEST_TIME 29 10061
TEST_TIME 30 10413
TEST_TIME 31 10271
TEST_TIME 32 10078
TEST_TIME 33 9919
TEST_TIME 34 9799
TEST_TIME 35 9919
TEST_TIME 36 9790
TEST_TIME 37 9618
TEST_TIME 38 9578
TEST_TIME 39 9524
TEST_TIME 40 9414
TEST_TIME 41 9416
TEST_TIME 42 9381
TEST_TIME 43 9215
TEST_TIME 44 9321
TEST_TIME 45 9297
TEST_TIME 46 9181
TEST_TIME 47 9158
TEST_TIME 48 9095
TEST_TIME 49 9153
TEST_TIME 50 9045
TEST_TIME 51 8959
TEST_TIME 52 8925
TEST_TIME 53 8937
TEST_TIME 54 8986
TEST_TIME 55 8930
TEST_TIME 56 8893
TEST_TIME 57 8948
TEST_TIME 58 8891
TEST_TIME 59 8839
TEST_TIME 60 9070
TEST_TIME 61 9118
TEST_TIME 62 9152
TEST_TIME 63 9229
TEST_TIME 64 9210
TEST_TIME 65 9244
TEST_TIME 66 9313
TEST_TIME 67 9327
TEST_TIME 68 9264
TEST_TIME 69 9206
TEST_TIME 70 9104
TEST_TIME 71 9093
TEST_TIME 72 9229
TEST_TIME 73 9193
TEST_TIME 74 9194
TEST_TIME 75 9192
TEST_TIME 76 9143
TEST_TIME 77 9111
TEST_TIME 78 9057
TEST_TIME 79 9032
TEST_TIME 80 8995
TEST_TIME 81 9004
TEST_TIME 82 9000
TEST_TIME 83 9013
TEST_TIME 84 8958
TEST_TIME 85 9073
TEST_TIME 86 9112
TEST_TIME 87 9078
TEST_TIME 88 9078
TEST_TIME 89 9125
TEST_TIME 90 9158
TEST_TIME 91 9137
TEST_TIME 92 9272
TEST_TIME 93 9268
TEST_TIME 94 9286
TEST_TIME 95 9238
TEST_TIME 96 9175
TEST_TIME 97 9141
TEST_TIME 98 9100
TEST_TIME 99 9055
TEST_TIME 100 9011
TEST_TIME 101 8986
TEST_TIME 102 8978
TEST_TIME 103 8954
TEST_TIME 104 8982
TEST_TIME 105 9016
TEST_TIME 106 9014
TEST_TIME 107 9047
TEST_TIME 108 9051
TEST_TIME 109 9020
TEST_TIME 110 9044
TEST_TIME 111 9066
TEST_TIME 112 9140
TEST_TIME 113 9132
TEST_TIME 114 9124
TEST_TIME 115 9146
TEST_TIME 116 9084
TEST_TIME 117 9047
TEST_TIME 118 9038
TEST_TIME 119 9019
TEST_TIME 120 8986

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-06-2022 01:40:34 to 24-06-2022 01:40:38.

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 1504972 1504972 0 0.00 0 17671 6 0 0
doc_list_biud 1504972 1504972 0 0.00 0 28726 26 0 0
sp_add_invoice_to_stock 5188 5078 110 2.12 15 182668 17600 0 110
sp_cancel_adding_invoice 5446 5446 0 0.00 4 46465 824 0 0
sp_cancel_client_order 1767 1767 0 0.00 3 12511 376 0 0
sp_cancel_customer_reserve 534151 534151 0 0.00 1 29652 56 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 6526 89 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 2 6530 98 0 0
sp_cancel_supplier_invoice 6013 6004 9 0.14 2 25925 756 0 9
sp_cancel_supplier_order 412 412 0 0.00 6 935704 92123 0 0
sp_cancel_write_off 8762 8762 0 0.00 1 11029 121 0 0
sp_client_order 20246 20246 0 0.00 4 21466 373 0 0
sp_customer_reserve 460042 460042 0 0.00 0 43107 177 0 0
sp_fill_shopping_cart 31144 31144 0 0.00 1 16544 42 0 0
sp_fill_shopping_cart_clo_res 460041 460041 0 0.00 0 22763 15 0 0
sp_get_clo_for_invoice 5077 5077 0 0.00 0 22340 372 0 0
sp_kill_cost_storno 15326 15326 0 0.00 0 15774 56 0 0
sp_kill_qstorno_ret_qs2qd 1083480 1083480 0 0.00 0 23811 30 0 0
sp_kill_qty_storno 571530 571530 0 0.00 0 28726 67 0 0
sp_lock_selected_doc 20876 20876 0 0.00 0 7964 4 0 0
sp_make_cost_storno 28108 28108 0 0.00 0 12505 52 0 0
sp_multiply_rows_for_pdistr 28108 28108 0 0.00 0 14541 28 0 0
sp_multiply_rows_for_qdistr 20246 20246 0 0.00 0 15651 166 0 0
sp_pay_from_customer 7191 7191 0 0.00 4 24839 129 0 0
sp_pay_to_supplier 5929 5929 0 0.00 5 11573 128 0 0
sp_payment_common 13120 13120 0 0.00 1 24833 31 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 12487 348 0 0
sp_qd_handle_on_invoice_upd_sts 10523 10523 0 0.00 0 28725 368 0 0
sp_qd_handle_on_reserve_upd_sts 9911 9911 0 0.00 0 14324 76 0 0
sp_reserve_write_off 9911 9911 0 0.00 6 14343 196 0 0
sp_supplier_invoice 5390 5390 0 0.00 5 76152 3610 0 0
sp_supplier_order 5510 5510 0 0.00 5 34635 1178 0 0
srv_aggregate_perf_data 103 103 0 0.00 2932 183751 17145 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1505 151616 47514 0 0
srv_find_qd_qs_mism 1031133 1031133 0 0.00 0 17670 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 26 71358 1221 0 0
srv_make_money_saldo 739 739 0 0.00 1 5549 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 188206 411763 350743 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5078 5078 0 0.00 0 16601 168 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 3447 83 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 4155 13 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 521 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 501 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 196 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 724 12 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 22036 1684 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 4282 24 0 0
t$perf-norm:sp_client_order 20246 20246 0 0.00 0 10605 20 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 8165 28 0 0
t$perf-norm:sp_pay_from_customer 7191 7191 0 0.00 0 6871 13 0 0
t$perf-norm:sp_pay_to_supplier 5929 5929 0 0.00 0 7041 15 0 0
t$perf-norm:sp_reserve_write_off 9911 9911 0 0.00 0 10394 13 0 0
t$perf-norm:sp_supplier_invoice 5390 5390 0 0.00 0 11278 18 0 0
t$perf-norm:sp_supplier_order 5510 5510 0 0.00 0 5299 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 46 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 266 7 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 716 20 0 0
t$perf-norm:srv_make_money_saldo 739 739 0 0.00 0 4816 40 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 13 7 0 0
v_add_invoice_to_stock 10374 10154 220 2.12 0 5826 18 0 220
v_all_customers 36748 36748 0 0.00 0 16 1 0 0
v_all_suppliers 30102 30102 0 0.00 0 83 1 0 0
v_all_wares 40492 40492 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 6152 19 0 0
v_cancel_client_order 3534 3534 0 0.00 0 6931 8 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 6403 17 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 735 3 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 6518 25 0 0
v_cancel_supplier_invoice 868 850 18 2.07 0 2071 18 0 18
v_cancel_supplier_order 824 824 0 0.00 0 5437 21 0 0
v_cancel_write_off 2054 2054 0 0.00 0 4791 16 0 0
v_max_id_clo_ord 5510 5510 0 0.00 0 13980 4 0 0
v_max_id_clo_res 1014 1014 0 0.00 0 8657 21 0 0
v_max_id_ord_sup 5388 5388 0 0.00 0 4063 7 0 0
v_max_non_paid_invoice 1776 1776 0 0.00 0 1792 5 0 0
v_max_non_paid_realizn 3918 3918 0 0.00 0 3854 6 0 0
v_min_id_clo_ord 5510 5510 0 0.00 0 10329 12 0 0
v_min_id_clo_res 1014 1014 0 0.00 0 2049 9 0 0
v_min_id_ord_sup 5388 5388 0 0.00 0 5693 9 0 0
v_min_non_paid_invoice 5929 5929 0 0.00 0 7434 19 0 0
v_min_non_paid_realizn 7191 7191 0 0.00 0 24826 27 0 0
v_our_firm 10290 10290 0 0.00 0 12 1 0 0
v_reserve_write_off 19822 19822 0 0.00 0 6400 7 0 0
x_lock_depdocs_on_canc_invoice 11025 11025 0 0.00 0 17477 105 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 23337 384 0 0
x_make_qstorno_1000_1200 5510 5510 0 0.00 1 34575 1090 0 0
x_make_qstorno_1200_2000 5390 5390 0 0.00 2 75183 3464 0 0
x_make_qstorno_2100_3300 460042 460042 0 0.00 0 43104 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-06-2022 01:40:39 to 24-06-2022 01:40:43.

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:24 57 8192000000 8824234000 8844255232 19539600 0.237 6 5 5 0 19772672 93776 139296
23:09:24 19955 8192000000 9897458688 10208649216 982528640 10.709 100 90 180 0 1012727328 6582752 23615936
23:10:48 26989 8192000000 9954104720 10275749888 1032497184 11.192 100 90 180 0 1064453840 9407024 22549632
23:12:18 27127 8192000000 9980710208 10314637312 1064539600 11.500 100 81 162 0 1095460640 10165376 20755664
23:13:51 11090 8192000000 10041208304 10388615168 1122690064 12.052 100 81 162 0 1153003904 11136784 19177056
23:15:12 10174 8192000000 10098964688 10460340224 1180344064 12.593 100 86 170 1 1213023568 12958320 19721184
23:16:25 3880 8192000000 10134234928 10502451200 1221485152 12.975 100 76 151 1 1250369696 12049248 16835296
23:17:32 2397 8192000000 10171837232 10544914432 1251954704 13.256 100 91 180 1 1285339264 14218064 19166496
23:18:36 1869 8192000000 10198248176 10576961536 1284612432 13.555 100 94 186 0 1318444384 13519264 20312688
23:19:40 26724 8192000000 10209545664 10604843008 1295738560 13.656 100 59 119 2 1322644384 12739264 14166560
23:21:07 7363 8192000000 10188378640 10598412288 1300544672 13.700 100 21 42 1 1312090288 7108784 4436832
23:22:16 7199 8192000000 10193746224 10614013952 1314100576 13.823 100 23 46 0 1325320128 5993840 5225712
23:23:26 44965 8192000000 10205867968 10629451776 1321030912 13.886 100 18 36 0 1331730656 5558224 5141520
23:25:13 12255 8192000000 10212318416 10634805248 1324766304 13.920 100 23 47 0 1335825168 5974816 5084048
23:26:28 7535 8192000000 10226941616 10645737472 1335215792 14.014 100 17 31 1 1344039008 5196640 3626576
23:27:36 8686 8192000000 10223896192 10643234816 1309454864 13.781 98 18 36 0 1322980864 9807568 3718432
23:28:48 39496 8192000000 10207324016 10613907456 1268891728 13.411 96 41 81 0 1283289872 4761920 9636224
23:30:30 6192 8192000000 10145072752 10550833152 1252559152 13.262 99 24 46 2 1264064144 5981872 5523120
23:31:37 8256 8192000000 10156844480 10557415424 1241605712 13.161 98 31 62 0 1254020240 5076528 7338000
23:32:45 10920 8192000000 10159276640 10563014656 1237848640 13.126 97 24 48 1 1248314864 4926592 5539632
23:33:56 20558 8192000000 10165025600 10573336576 1263153456 13.359 99 23 46 0 1273965648 5261552 5550640
23:35:18 11500 8192000000 10183267264 10595848192 1292203952 13.624 100 16 33 0 1301669616 5632288 3833376
23:36:30 15979 8192000000 10193209104 10610348032 1308004896 13.768 100 16 32 0 1317151296 5522496 3623904
23:37:48 22947 8192000000 10189686768 10616266752 1313233536 13.815 100 24 48 0 1325185184 5809984 6141664
23:39:43 8025 8192000000 10249545952 10661662720 1343306608 14.087 100 49 96 1 1365515264 10538336 11670320
23:41:03 10949 8192000000 10272481648 10690437120 1365442320 14.286 100 47 94 0 1386883408 10933536 10507552
23:42:24 56884 8192000000 10296203024 10719698944 1387340624 14.482 100 42 84 1 1413152928 16132352 9679952
23:44:29 8105 8192000000 10320988960 10742788096 1403463136 14.626 100 43 86 0 1431480016 16746544 11270336
23:45:39 22553 8192000000 10322929776 10745790464 1401630544 14.610 100 45 90 4 1434432048 21262592 11538912
23:47:08 19301 8192000000 10329251888 10760732672 1407938576 14.666 100 41 82 1 1440718832 22042784 10737472
23:48:30 4724 8192000000 10348367888 10767605760 1411326064 14.696 100 58 114 1 1449982304 24957568 13698672
23:49:40 1502 8192000000 10348858416 10761965568 1406643344 14.654 100 82 163 3 1451999440 25188656 20167440
23:50:44 2903 8192000000 10362021760 10776698880 1415746080 14.735 100 78 154 1 1466673040 32207280 18719680
23:51:52 3852 8192000000 10337696112 10752929792 1388513728 14.493 99 81 160 0 1437431632 28871488 20046416
23:53:02 3670 8192000000 10337785056 10748035072 1386093344 14.471 100 78 155 0 1442390192 37039920 19256928
23:54:10 1987 8192000000 10347941968 10751586304 1390448784 14.510 100 87 172 3 1444121120 32613776 21058560
23:55:16 9584 8192000000 10335112144 10756366336 1399551552 14.591 100 67 134 1 1443512768 27168000 16793216
23:56:29 12960 8192000000 10280488400 10708770816 1337952928 14.039 97 31 58 1 1372930208 26337328 8639952
23:57:53 22068 8192000000 10281968256 10711343104 1339700576 14.055 98 34 67 1 1376176864 27232432 9243856
23:59:20 5355 8192000000 10296190960 10716917760 1362644640 14.261 100 40 80 2 1400110480 27328624 10137216
00:00:42 12715 8192000000 10262907168 10683142144 1314326032 13.825 98 25 51 0 1341904192 21401936 6176224
00:01:59 7784 8192000000 10276074896 10702229504 1346812272 14.119 100 29 58 3 1381313840 27346128 7155440
00:03:13 16978 8192000000 10296763152 10727387136 1353371376 14.178 99 23 46 2 1385487232 25938880 6176976
00:04:56 47852 8192000000 10274016160 10715099136 1352634528 14.171 99 37 74 1 1384430800 22313200 9483072
00:07:00 35206 8192000000 10287749568 10730340352 1359122560 14.229 99 21 42 1 1390604176 26263200 5218416
00:08:40 24811 8192000000 10297642720 10724790272 1381726384 14.432 100 40 84 2 1396725632 6399504 8599744
00:10:10 79606 8192000000 10283393536 10731347968 1366174336 14.293 98 17 34 1 1380029408 10299200 3555872
00:12:30 57807 8192000000 10286869264 10742120448 1388577872 14.493 100 28 56 0 1403350704 7967280 6805552
00:14:29 64163 8192000000 10301871488 10748780544 1393922080 14.541 100 26 50 2 1412226384 12535488 5768816
00:16:38 81779 8192000000 10302623520 10760167424 1386392160 14.474 99 14 29 0 1403013888 12991168 3630560
00:19:03 50887 8192000000 10303375328 10748399616 1370254448 14.329 98 28 56 0 1387550288 10927920 6367920
00:20:58 79277 8192000000 10311567392 10762223616 1398323424 14.580 100 17 34 0 1412956544 10477424 4155696
00:23:28 54059 8192000000 10289598224 10751148032 1398226752 14.579 100 25 50 0 1411916608 8138272 5551584
00:25:24 45489 8192000000 10301193936 10756308992 1389354768 14.500 99 22 44 1 1404852448 10745472 4752208
00:27:10 67479 8192000000 10289215760 10758868992 1379900496 14.416 98 17 34 2 1396684704 12677248 4106960
00:29:48 96133 8192000000 10307825584 10766471168 1400451440 14.599 100 17 34 0 1421293328 16626848 4215040
00:32:28 47942 8192000000 10282845696 10742824960 1341367344 14.070 96 8 16 0 1354448064 11687152 1393568
00:34:18 51775 8192000000 10282644160 10740416512 1357327008 14.213 97 20 40 0 1370792896 9135888 4330000
00:36:15 18390 8192000000 10314310000 10753490944 1378873008 14.406 99 34 69 1 1397274640 10330640 8070992
00:37:45 50540 8192000000 10315794832 10783408128 1402534864 14.618 100 17 34 0 1432396176 25574912 4286400
00:39:40 114049 8192000000 10279088704 10738151424 1312729984 13.811 93 15 30 0 1326978000 11157840 3090176
00:42:39 50970 8192000000 10276417504 10716577792 1323849952 13.912 97 31 62 0 1342483792 12303904 6329936
00:44:32 41635 8192000000 10241837072 10674434048 1244152080 13.184 92 15 29 0 1253150944 6197616 2801248
00:46:16 44580 8192000000 10286245504 10716270592 1327325136 13.943 98 48 96 2 1345838368 7481712 11031520
00:48:24 45861 8192000000 10256238992 10693156864 1317915488 13.858 97 32 63 1 1339956272 14486656 7554128
00:50:29 50767 8192000000 10255498432 10716925952 1349791408 14.146 100 16 32 2 1373940656 20218160 3931088
00:52:25 105476 8192000000 10252681328 10713026560 1316234480 13.843 97 10 20 1 1336556864 17954224 2368160
00:55:23 77019 8192000000 10243268080 10695135232 1330985616 13.976 98 4 8 0 1340177296 8372848 818832
00:57:43 69264 8192000000 10276439312 10726146048 1365974944 14.291 100 27 54 1 1381473168 9371584 6126640
00:59:54 73396 8192000000 10259826832 10709192704 1319717360 13.874 96 19 38 0 1338063792 14106128 4240304
01:03:02 61701 8192000000 10267801984 10719977472 1353773568 14.181 99 17 34 3 1374314032 16656208 3884256
01:06:22 74472 8192000000 10282800288 10733277184 1359722368 14.235 99 20 40 1 1379250992 14564608 4964016
01:08:37 34683 8192000000 10288928688 10728034304 1353790848 14.182 98 27 52 2 1367336400 7739936 5805616
01:10:13 60020 8192000000 10301383968 10771255296 1393190064 14.534 100 19 38 1 1414646352 17351392 4104896
01:12:14 151616 8192000000 10288250160 10745827328 1329784048 13.965 95 16 32 0 1349399648 16100992 3514608
01:15:46 65935 8192000000 10281089520 10745921536 1374959056 14.371 99 9 18 0 1388695312 11947200 1789056
01:18:03 104108 8192000000 10292349008 10749992960 1373151360 14.355 99 14 28 1 1387664080 11220496 3292224
01:20:55 67109 8192000000 10311318000 10764070912 1389632384 14.503 99 18 36 1 1405480224 12265968 3581872
01:23:07 62687 8192000000 10309906640 10765078528 1379775392 14.415 98 14 29 1 1393878592 10841520 3261680
01:25:09 89242 8192000000 10297543168 10764292096 1375977200 14.381 98 23 44 0 1394745568 13498864 5269504
01:27:39 28776 8192000000 10315312928 10759630848 1365366160 14.286 97 35 70 0 1385860768 12778848 7715760
01:29:30 33419 8192000000 10294437792 10738618368 1343128512 14.086 96 26 52 0 1360324912 11457968 5738432
01:31:04 103308 8192000000 10287413088 10735525888 1288658192 13.592 93 12 24 1 1302714640 11147392 2909056
01:33:48 88751 8192000000 10266738304 10712297472 1336523664 14.026 98 15 30 0 1348474032 8654800 3295568
01:36:24 49092 8192000000 10247135056 10691907584 1330389184 13.971 98 27 54 0 1346160448 10007952 5763312
01:38:14 12779 8192000000 9222888496 9496612864 387101712 4.512 29 2 4 0 391502192 4058832 341648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 24-06-2022 01:40:45 to 24-06-2022 01:40:59.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-06-2022 01:41:00 to 24-06-2022 01:41:02.

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          241530
MON$OLDEST_ACTIVE               241531
MON$OLDEST_SNAPSHOT             241531
MON$NEXT_TRANSACTION            241531
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                       2025120
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:345: 
:346: mscs-test-161.budzdorov.ru    Fri Jun 24 01:38:48 2022
:347:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:348: 
:349: 
:350: mscs-test-161.budzdorov.ru    Fri Jun 24 01:38:50 2022
:351:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:352: 
--- end of diff output ---

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

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