Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2022 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 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 15524100 kB
MemAvailable 30481420 kB
Buffers 0 kB
Cached 16186152 kB
SwapCached 2060 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-18 19:38:30
DTS_END                         2022-10-18 19:38:30.2950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_PAY_FROM_CUSTOMER(18:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48076
TRN_ID                          253425
ATT_ID                          715
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: 9091.20 21498 1090944
customer order: creation 187.98 342 22557
customer order: refuse 15.74 334 1889
order to supplier: creation 50.25 1261 6030
order to supplier: removal 3.30 80627 396
invoice (draft): creation 50.66 3425 6079
invoice (draft): removal 48.56 719 5827
invoice accept: apply 44.11 16650 5293
invoice accept: cancel 44.26 764 5311
customer reserve: creation 4031.65 166 483798
customer reserve: removal 4295.35 48 515442
realization accept: apply 91.75 170 11010
realization accept: cancel 87.37 101 10484
payment from customer: creation 63.68 105 7642
payment from customer: removal 6.66 67 799
payment to supplier: creation 53.24 117 6389
payment to supplier: removal 3.57 58 428
service: total inventory turnovers 5.01 865 601
service: total monetary turnovers 7.17 31 860
service: aggregate perf. data 0.88 16433 105
service: refresh index statistics 0.03 307679 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 18-10-2022 19:39:38 to 18-10-2022 19:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4104
TEST_TIME 2 5127
TEST_TIME 3 5005
TEST_TIME 4 6172
TEST_TIME 5 5809
TEST_TIME 6 6149
TEST_TIME 7 5928
TEST_TIME 8 5654
TEST_TIME 9 5654
TEST_TIME 10 6058
TEST_TIME 11 6155
TEST_TIME 12 6072
TEST_TIME 13 6088
TEST_TIME 14 6432
TEST_TIME 15 6904
TEST_TIME 16 7555
TEST_TIME 17 7865
TEST_TIME 18 8047
TEST_TIME 19 8293
TEST_TIME 20 9413
TEST_TIME 21 9214
TEST_TIME 22 9111
TEST_TIME 23 9007
TEST_TIME 24 9228
TEST_TIME 25 9509
TEST_TIME 26 9722
TEST_TIME 27 9952
TEST_TIME 28 9900
TEST_TIME 29 9960
TEST_TIME 30 10291
TEST_TIME 31 10138
TEST_TIME 32 10136
TEST_TIME 33 10058
TEST_TIME 34 9984
TEST_TIME 35 9846
TEST_TIME 36 9869
TEST_TIME 37 9803
TEST_TIME 38 9790
TEST_TIME 39 9692
TEST_TIME 40 9559
TEST_TIME 41 9446
TEST_TIME 42 9557
TEST_TIME 43 9445
TEST_TIME 44 9409
TEST_TIME 45 9297
TEST_TIME 46 9213
TEST_TIME 47 9204
TEST_TIME 48 9146
TEST_TIME 49 9137
TEST_TIME 50 9141
TEST_TIME 51 9020
TEST_TIME 52 9100
TEST_TIME 53 9112
TEST_TIME 54 9064
TEST_TIME 55 9106
TEST_TIME 56 9077
TEST_TIME 57 9171
TEST_TIME 58 9176
TEST_TIME 59 9248
TEST_TIME 60 9219
TEST_TIME 61 9419
TEST_TIME 62 9390
TEST_TIME 63 9472
TEST_TIME 64 9452
TEST_TIME 65 9444
TEST_TIME 66 9425
TEST_TIME 67 9450
TEST_TIME 68 9383
TEST_TIME 69 9305
TEST_TIME 70 9250
TEST_TIME 71 9232
TEST_TIME 72 9203
TEST_TIME 73 9196
TEST_TIME 74 9171
TEST_TIME 75 9119
TEST_TIME 76 9043
TEST_TIME 77 9076
TEST_TIME 78 9092
TEST_TIME 79 9102
TEST_TIME 80 9115
TEST_TIME 81 9090
TEST_TIME 82 9208
TEST_TIME 83 9226
TEST_TIME 84 9294
TEST_TIME 85 9274
TEST_TIME 86 9339
TEST_TIME 87 9364
TEST_TIME 88 9323
TEST_TIME 89 9290
TEST_TIME 90 9202
TEST_TIME 91 9221
TEST_TIME 92 9160
TEST_TIME 93 9198
TEST_TIME 94 9187
TEST_TIME 95 9151
TEST_TIME 96 9128
TEST_TIME 97 9154
TEST_TIME 98 9153
TEST_TIME 99 9145
TEST_TIME 100 9122
TEST_TIME 101 9100
TEST_TIME 102 9140
TEST_TIME 103 9191
TEST_TIME 104 9200
TEST_TIME 105 9166
TEST_TIME 106 9197
TEST_TIME 107 9177
TEST_TIME 108 9189
TEST_TIME 109 9216
TEST_TIME 110 9230
TEST_TIME 111 9171
TEST_TIME 112 9122
TEST_TIME 113 9108
TEST_TIME 114 9082
TEST_TIME 115 9049
TEST_TIME 116 9119
TEST_TIME 117 9097
TEST_TIME 118 9099
TEST_TIME 119 9132
TEST_TIME 120 9092

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-10-2022 19:39:42 to 18-10-2022 19:39:46.

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 1540687 1540687 0 0.00 0 31920 6 0 0
doc_list_biud 1540687 1540687 0 0.00 0 33172 22 0 0
sp_add_invoice_to_stock 5475 5293 182 3.32 34 180376 16650 0 182
sp_cancel_adding_invoice 5311 5311 0 0.00 3 42789 764 0 0
sp_cancel_client_order 1889 1889 0 0.00 3 11441 335 0 0
sp_cancel_customer_reserve 515442 515442 0 0.00 0 33044 49 0 0
sp_cancel_pay_from_customer 799 799 0 0.00 2 3858 68 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 3986 59 0 0
sp_cancel_supplier_invoice 5841 5827 14 0.23 2 22736 720 0 14
sp_cancel_supplier_order 396 396 0 0.00 5 994981 80627 0 0
sp_cancel_write_off 10484 10484 0 0.00 1 22165 102 0 0
sp_client_order 22557 22557 0 0.00 3 31173 342 0 0
sp_customer_reserve 483798 483798 0 0.00 0 86428 167 0 0
sp_fill_shopping_cart 34654 34654 0 0.00 1 20366 41 0 0
sp_fill_shopping_cart_clo_res 483796 483796 0 0.00 0 11442 11 0 0
sp_get_clo_for_invoice 5291 5291 0 0.00 0 20498 282 0 0
sp_kill_cost_storno 17022 17022 0 0.00 0 15424 45 0 0
sp_kill_qstorno_ret_qs2qd 1047591 1047591 0 0.00 0 28329 27 0 0
sp_kill_qty_storno 555650 555650 0 0.00 0 33170 59 0 0
sp_lock_selected_doc 22486 22486 0 0.00 0 6917 6 0 0
sp_make_cost_storno 30332 30332 0 0.00 0 11363 51 0 0
sp_multiply_rows_for_pdistr 30332 30332 0 0.00 0 5698 25 0 0
sp_multiply_rows_for_qdistr 22557 22557 0 0.00 0 12769 151 0 0
sp_pay_from_customer 7642 7642 0 0.00 4 18799 106 0 0
sp_pay_to_supplier 6389 6389 0 0.00 4 10718 118 0 0
sp_payment_common 14031 14031 0 0.00 1 18794 28 0 0
sp_qd_handle_on_cancel_clo 1889 1889 0 0.00 0 11436 299 0 0
sp_qd_handle_on_invoice_upd_sts 10602 10602 0 0.00 0 33168 309 0 0
sp_qd_handle_on_reserve_upd_sts 11010 11010 0 0.00 0 10187 55 0 0
sp_reserve_write_off 11010 11010 0 0.00 6 16644 171 0 0
sp_supplier_invoice 6079 6079 0 0.00 6 98152 3426 0 0
sp_supplier_order 6030 6030 0 0.00 5 34915 1262 0 0
srv_aggregate_perf_data 105 105 0 0.00 2118 365683 16434 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 784 138353 43943 0 0
srv_find_qd_qs_mism 1040546 1040546 0 0.00 0 31919 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 23 15331 865 0 0
srv_make_money_saldo 860 860 0 0.00 1 2508 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 172323 445354 307679 0 0
t$perf-abend:sp_add_invoice_to_stock 182 182 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5293 5293 0 0.00 0 18431 175 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 0 5321 120 0 0
t$perf-norm:sp_cancel_client_order 1889 1889 0 0.00 0 4758 19 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 2636 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 799 799 0 0.00 0 4219 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 1185 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 1888 24 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 36248 1522 0 0
t$perf-norm:sp_cancel_write_off 1132 1132 0 0.00 0 10371 17 0 0
t$perf-norm:sp_client_order 22557 22557 0 0.00 0 7800 16 0 0
t$perf-norm:sp_customer_reserve 1107 1107 0 0.00 0 4911 8 0 0
t$perf-norm:sp_pay_from_customer 7642 7642 0 0.00 0 4132 10 0 0
t$perf-norm:sp_pay_to_supplier 6389 6389 0 0.00 0 21200 17 0 0
t$perf-norm:sp_reserve_write_off 11010 11010 0 0.00 0 10776 11 0 0
t$perf-norm:sp_supplier_invoice 6079 6079 0 0.00 0 6746 17 0 0
t$perf-norm:sp_supplier_order 6030 6030 0 0.00 0 5883 13 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 814 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 8 0 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 6407 29 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 685 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 9 5 0 0
v_add_invoice_to_stock 10944 10580 364 3.32 0 5546 20 0 364
v_all_customers 41096 41096 0 0.00 0 32 1 0 0
v_all_suppliers 33196 33196 0 0.00 0 23 1 0 0
v_all_wares 45114 45114 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 6600 16 0 0
v_cancel_client_order 3778 3778 0 0.00 0 10657 9 0 0
v_cancel_customer_prepayment 1598 1598 0 0.00 0 1644 4 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 4176 7 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 519 3 0 0
v_cancel_supplier_invoice 762 734 28 3.67 0 1220 15 0 28
v_cancel_supplier_order 792 792 0 0.00 0 555 3 0 0
v_cancel_write_off 2264 2264 0 0.00 0 5930 19 0 0
v_max_id_clo_ord 6029 6029 0 0.00 0 473 1 0 0
v_max_id_clo_res 1107 1107 0 0.00 0 1871 17 0 0
v_max_id_ord_sup 6068 6068 0 0.00 0 5127 7 0 0
v_max_non_paid_invoice 1888 1888 0 0.00 0 880 3 0 0
v_max_non_paid_realizn 4151 4151 0 0.00 0 2569 5 0 0
v_min_id_clo_ord 6029 6029 0 0.00 0 5147 7 0 0
v_min_id_clo_res 1107 1107 0 0.00 0 3496 8 0 0
v_min_id_ord_sup 6068 6068 0 0.00 0 5959 7 0 0
v_min_non_paid_invoice 6389 6389 0 0.00 0 6582 19 0 0
v_min_non_paid_realizn 7642 7642 0 0.00 0 6922 18 0 0
v_our_firm 11000 11000 0 0.00 0 54 1 0 0
v_reserve_write_off 22020 22020 0 0.00 0 9415 8 0 0
x_lock_depdocs_on_canc_invoice 10771 10771 0 0.00 0 11624 95 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 8377 319 0 0
x_make_qstorno_1000_1200 6030 6030 0 0.00 1 34902 1186 0 0
x_make_qstorno_1200_2000 6079 6079 0 0.00 2 87505 3269 0 0
x_make_qstorno_2100_3300 483798 483798 0 0.00 0 86413 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2022 19:39:47 to 18-10-2022 19:39:51.

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:08:34 1 8192000000 8797958960 8808792064 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:35 38597 8192000000 9908913936 10219425792 988035056 10.762 100 92 184 0 1018809952 6331280 24443616
17:11:17 25217 8192000000 9948609264 10270105600 1031880976 11.187 100 87 174 0 1062866512 8162816 22822720
17:12:53 7907 8192000000 9995262880 10328526848 1074881680 11.599 100 90 180 0 1106757008 9333152 22542176
17:14:06 8445 8192000000 10030787776 10378055680 1119374864 12.021 100 73 146 1 1146345200 9893072 17077264
17:15:18 5487 8192000000 10088022112 10449276928 1171429936 12.510 100 77 154 0 1201207568 12209472 17568160
17:16:26 2990 8192000000 10128827520 10503143424 1220308512 12.965 100 74 145 0 1248478752 12217152 15953088
17:17:30 1465 8192000000 10160348832 10538475520 1256092544 13.294 100 82 161 0 1285951584 12474224 17384816
17:18:33 1256 8192000000 10201890736 10579681280 1280978656 13.522 100 91 182 2 1316153872 14783600 20391616
17:19:36 24035 8192000000 10204571440 10599645184 1297203712 13.670 100 61 118 1 1319814416 9216864 13393840
17:21:00 4213 8192000000 10201191984 10608340992 1308686864 13.774 100 42 83 2 1325256000 6890304 9678832
17:22:08 8375 8192000000 10203134240 10616139776 1314924688 13.831 100 18 37 0 1325173424 6171168 4077568
17:23:17 47778 8192000000 10237999296 10638721024 1324863520 13.921 100 49 99 1 1342527040 5793184 11870336
17:25:06 6289 8192000000 10221327472 10640756736 1329878064 13.966 100 25 49 0 1341945088 6704432 5362592
17:26:12 7434 8192000000 10220661168 10638979072 1322471440 13.899 99 26 55 1 1334271184 5395744 6404000
17:27:22 10862 8192000000 10216280336 10637791232 1319479520 13.872 99 19 38 0 1329160496 5187792 4493184
17:28:35 46917 8192000000 10185625232 10599084032 1231103888 13.064 93 35 68 0 1244276976 5483248 7689840
17:30:23 4880 8192000000 10184016192 10586320896 1269972000 13.421 99 28 56 0 1283282288 7037408 6272880
17:31:28 7246 8192000000 10119870592 10514087936 1220119104 12.963 99 19 35 1 1229300640 4957328 4224208
17:32:37 13549 8192000000 10192973952 10575515648 1271277600 13.433 100 28 57 0 1284405376 6107568 7020208
17:33:52 6870 8192000000 10193147408 10582913024 1271639824 13.437 99 66 136 0 1297088736 10494912 14954000
17:34:59 6303 8192000000 10186962112 10602385408 1300897216 13.703 100 31 60 0 1317109280 9142928 7069136
17:36:08 8122 8192000000 10212434000 10618814464 1313375664 13.817 100 28 54 0 1324771840 5026352 6369824
17:37:19 12081 8192000000 10202076352 10622386176 1314279824 13.825 100 16 32 0 1322667728 5035008 3352896
17:38:31 19232 8192000000 10233652800 10648903680 1332030480 13.985 100 35 69 1 1345635264 5484528 8120256
17:39:58 9119 8192000000 10257601264 10670075904 1349120800 14.140 100 43 85 2 1365883024 7730112 9032112
17:41:11 19437 8192000000 10274740928 10692857856 1370529984 14.332 100 54 107 0 1394179024 11825408 11823632
17:42:34 9115 8192000000 10293239584 10717904896 1379560064 14.413 100 43 86 0 1410314464 20634960 10119440
17:43:46 12464 8192000000 10291871808 10715373568 1382256496 14.437 100 50 98 1 1408583136 14957776 11368864
17:45:08 5301 8192000000 10322085376 10742734848 1402385888 14.616 100 54 105 0 1430279648 15991008 11902752
17:46:18 4194 8192000000 10316031360 10737426432 1397497632 14.573 99 55 108 1 1427625744 17900176 12227936
17:47:28 9919 8192000000 10320398304 10744102912 1395001200 14.550 99 46 88 0 1428102448 21821040 11280208
17:48:41 11216 8192000000 10324609440 10757050368 1415552112 14.733 100 48 93 1 1449712336 22506528 11653696
17:50:00 3451 8192000000 10358377824 10784174080 1431673312 14.876 100 64 125 2 1472676832 25524992 15478528
17:51:06 2252 8192000000 10349986736 10768953344 1416610320 14.743 100 64 127 0 1460118000 27973392 15534288
17:52:11 6484 8192000000 10334911440 10754387968 1402725488 14.619 100 57 114 0 1442873808 26508944 13639376
17:53:23 783 8192000000 10311193184 10716438528 1362588768 14.261 99 87 176 3 1415316448 30737024 21990656
17:54:30 6533 8192000000 10302621824 10719821824 1354644016 14.189 99 62 123 1 1401818832 31756464 15418352
17:55:42 7471 8192000000 10262308816 10678759424 1337184480 14.032 100 45 88 0 1375347280 26544480 11618320
17:56:52 8102 8192000000 10253030416 10664771584 1321086992 13.887 99 40 76 0 1356663120 25701520 9874608
17:58:07 10018 8192000000 10242594720 10660937728 1301485248 13.709 98 32 64 0 1345141872 35197296 8459328
17:59:20 39192 8192000000 10270349232 10688483328 1343141536 14.086 100 46 93 0 1371412672 17003408 11267728
18:01:08 8522 8192000000 10245057504 10670448640 1330800736 13.974 100 33 66 0 1358705296 19285248 8619312
18:02:27 15926 8192000000 10249853488 10678575104 1322395648 13.898 99 22 44 0 1354722352 26461376 5865328
18:03:48 27716 8192000000 10263664416 10683973632 1319731792 13.874 98 24 47 0 1347023888 20651232 6640864
18:05:16 19815 8192000000 10266490768 10697277440 1340517200 14.062 100 24 48 0 1368787696 22025072 6245424
18:06:53 15813 8192000000 10269841552 10705559552 1354233728 14.186 100 34 68 0 1380531392 17808336 8489328
18:08:10 40257 8192000000 10290682720 10734559232 1365003968 14.282 100 18 36 0 1395851392 26574464 4272960
18:09:55 53381 8192000000 10299155408 10736431104 1377884512 14.398 100 19 36 0 1401312512 19579104 3848896
18:12:15 90861 8192000000 10286832336 10742603776 1386929776 14.478 100 23 46 0 1405780000 13341632 5508592
18:14:48 13653 8192000000 10277544608 10737459200 1379263648 14.410 100 14 27 0 1394398032 12515664 2618720
18:16:02 64051 8192000000 10264505600 10719084544 1364805648 14.280 99 10 18 0 1373873280 6975952 2091680
18:18:06 63085 8192000000 10305195968 10756186112 1397846240 14.576 100 26 52 0 1413322672 9234784 6241648
18:20:16 48940 8192000000 10300215632 10764976128 1399489232 14.590 100 8 16 0 1413783808 12666448 1628128
18:22:18 59653 8192000000 10303642384 10771484672 1412026720 14.702 100 17 34 0 1423957984 8043616 3887648
18:24:51 81214 8192000000 10313476192 10783154176 1415982976 14.737 100 17 35 0 1435693040 15737600 3972464
18:27:13 48463 8192000000 10325575232 10793390080 1424378272 14.812 100 23 46 0 1445998944 16280496 5340176
18:29:04 66734 8192000000 10310321984 10777853952 1405763296 14.646 99 11 22 0 1422705680 14076624 2865760
18:31:23 47887 8192000000 10274797920 10737803264 1336043840 14.022 95 11 22 0 1345907264 7563536 2299888
18:33:11 50767 8192000000 10258436848 10714824704 1299962384 13.695 94 21 42 0 1313979920 9630816 4386720
18:35:07 37038 8192000000 10249482224 10707156992 1314256368 13.825 96 35 70 0 1337277696 14631184 8390144
18:36:47 71027 8192000000 10255800128 10701398016 1278819024 13.502 94 16 32 0 1299933264 17218784 3895456
18:38:58 60247 8192000000 10269274112 10731114496 1333730192 14.001 98 23 47 1 1362149792 22462288 5957312
18:41:01 82274 8192000000 10265331456 10712502272 1292757120 13.629 94 11 22 0 1308015536 13058304 2200112
18:43:25 58764 8192000000 10260138592 10696564736 1320335424 13.880 97 41 82 0 1338748288 9520912 8891952
18:45:27 137190 8192000000 10255936720 10695200768 1297862768 13.676 95 32 64 1 1317218576 11807488 7548320
18:48:51 36498 8192000000 10254583504 10694590464 1333293264 13.997 99 25 50 0 1351496976 12673200 5530512
18:50:30 68637 8192000000 10246767872 10708037632 1352016864 14.166 100 20 40 0 1372051408 14878992 5155552
18:53:36 31465 8192000000 10260796608 10714931200 1353931824 14.183 99 18 37 0 1367669184 8897360 4840000
18:55:21 58713 8192000000 10267452272 10735763456 1354982000 14.192 99 6 12 0 1374953040 18287744 1683296
18:57:26 21908 8192000000 10284516080 10730270720 1368922608 14.317 99 30 58 2 1385179424 9823664 6433152
18:58:52 53225 8192000000 10298217280 10756972544 1384680416 14.458 100 15 30 0 1407292544 19092192 3519936
19:00:45 41899 8192000000 10297518944 10746355712 1364819296 14.281 98 17 34 0 1378119472 9620192 3679984
19:02:30 73488 8192000000 10309784800 10750345216 1390192112 14.508 100 31 61 0 1419591232 22536528 6862592
19:04:47 138353 8192000000 10313655408 10764226560 1377589280 14.395 99 10 20 0 1392235744 12224768 2421696
19:08:06 117522 8192000000 10311858016 10778656768 1410315920 14.687 100 32 64 0 1435431376 17711248 7404208
19:11:07 49060 8192000000 10315834560 10789253120 1417060416 14.747 100 7 14 0 1429668848 11213488 1394944
19:13:03 70983 8192000000 10303392480 10764898304 1381531600 14.430 98 17 34 0 1396181552 11271616 3378336
19:15:14 78480 8192000000 10302285664 10770554880 1398695696 14.583 99 12 24 0 1413749680 11880400 3173584
19:17:33 31237 8192000000 10296392768 10752618496 1361521040 14.251 97 13 26 0 1375426848 11180384 2725424
19:19:08 30204 8192000000 10319052736 10768146432 1397124000 14.569 100 25 50 2 1414070688 11178240 5768448
19:20:39 137069 8192000000 10297230560 10751303680 1338373984 14.043 96 9 18 0 1351623904 11523520 1726400
19:23:57 53715 8192000000 10283330848 10733158400 1337025328 14.031 97 24 48 0 1355043200 12369552 5648320
19:25:52 26173 8192000000 10295926208 10739240960 1380673568 14.423 100 29 58 1 1397379968 10484304 6222096
19:27:28 36167 8192000000 10263549200 10701094912 1279142208 13.505 94 40 79 0 1300010624 11744736 9123680
19:29:07 57908 8192000000 10220657600 10659663872 1301778128 13.711 98 24 49 0 1317953648 10360208 5815312
19:31:09 59875 8192000000 10256637456 10707087360 1337407824 14.034 99 18 36 0 1363086800 21350928 4328048
19:33:16 65713 8192000000 10225375904 10678353920 1298492912 13.682 97 10 20 0 1319753184 18808976 2451296
19:35:23 40981 8192000000 10233130864 10672242688 1305742720 13.747 98 15 30 0 1322467680 13280512 3444448
19:37:09 73328 8192000000 10241116256 10679975936 1274424992 13.462 95 17 34 0 1289973552 10995568 4552992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-10-2022 19:39:53 to 18-10-2022 19:40:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 182 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-10-2022 19:40:09 to 18-10-2022 19:40:11.

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          252421
MON$OLDEST_ACTIVE               253596
MON$OLDEST_SNAPSHOT             253596
MON$NEXT_TRANSACTION            253596
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                       2023296
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33635 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33635 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33635 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33635 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 ---
:103: 
:104: mscs-test-161.budzdorov.ru    Tue Oct 18 19:38:34 2022
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:106: 
--- end of diff output ---

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

18.10.22 19:40:12. 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-10-18 19:40:15 No severe PSQL-related problems occured
18.10.22 19:40:16. End of report.