Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2022 19: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 14889156 kB
MemAvailable 30087632 kB
Buffers 0 kB
Cached 16832564 kB
SwapCached 3436 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-12-18 19:38:33
DTS_END                         2022-12-18 19:38:33.3710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55296
TRN_ID                          227303
ATT_ID                          611
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: 9313.45 24831 1117614
customer order: creation 156.26 375 18751
customer order: refuse 13.28 406 1594
order to supplier: creation 42.31 1308 5077
order to supplier: removal 3.43 112956 411
invoice (draft): creation 41.74 3354 5009
invoice (draft): removal 56.56 794 6787
invoice accept: apply 39.07 18451 4688
invoice accept: cancel 51.16 775 6139
customer reserve: creation 3677.56 178 441307
customer reserve: removal 4937.85 61 592542
realization accept: apply 75.53 196 9064
realization accept: cancel 101.51 107 12181
payment from customer: creation 53.91 119 6469
payment from customer: removal 5.38 71 646
payment to supplier: creation 44.22 146 5306
payment to supplier: removal 2.87 65 344
service: total inventory turnovers 4.07 1488 488
service: total monetary turnovers 6.12 32 734
service: aggregate perf. data 0.61 33498 73
service: refresh index statistics 0.03 322247 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-12-2022 19:40:26 to 18-12-2022 19:40:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5396
TEST_TIME 2 4523
TEST_TIME 3 5430
TEST_TIME 4 5318
TEST_TIME 5 5111
TEST_TIME 6 4920
TEST_TIME 7 5195
TEST_TIME 8 5589
TEST_TIME 9 5969
TEST_TIME 10 6286
TEST_TIME 11 6562
TEST_TIME 12 6855
TEST_TIME 13 7128
TEST_TIME 14 7479
TEST_TIME 15 7513
TEST_TIME 16 7550
TEST_TIME 17 7531
TEST_TIME 18 7801
TEST_TIME 19 8130
TEST_TIME 20 8096
TEST_TIME 21 8095
TEST_TIME 22 8185
TEST_TIME 23 8313
TEST_TIME 24 8993
TEST_TIME 25 9343
TEST_TIME 26 9502
TEST_TIME 27 9500
TEST_TIME 28 9868
TEST_TIME 29 10090
TEST_TIME 30 10624
TEST_TIME 31 10882
TEST_TIME 32 10909
TEST_TIME 33 10766
TEST_TIME 34 10660
TEST_TIME 35 10463
TEST_TIME 36 10646
TEST_TIME 37 10481
TEST_TIME 38 10355
TEST_TIME 39 10212
TEST_TIME 40 10140
TEST_TIME 41 10027
TEST_TIME 42 9868
TEST_TIME 43 9880
TEST_TIME 44 9771
TEST_TIME 45 9776
TEST_TIME 46 9713
TEST_TIME 47 9597
TEST_TIME 48 9445
TEST_TIME 49 9438
TEST_TIME 50 9564
TEST_TIME 51 9384
TEST_TIME 52 9358
TEST_TIME 53 9309
TEST_TIME 54 9277
TEST_TIME 55 9319
TEST_TIME 56 9281
TEST_TIME 57 9156
TEST_TIME 58 9043
TEST_TIME 59 9004
TEST_TIME 60 8949
TEST_TIME 61 9033
TEST_TIME 62 9131
TEST_TIME 63 9114
TEST_TIME 64 9213
TEST_TIME 65 9167
TEST_TIME 66 9188
TEST_TIME 67 9248
TEST_TIME 68 9236
TEST_TIME 69 9261
TEST_TIME 70 9343
TEST_TIME 71 9422
TEST_TIME 72 9429
TEST_TIME 73 9426
TEST_TIME 74 9359
TEST_TIME 75 9247
TEST_TIME 76 9283
TEST_TIME 77 9256
TEST_TIME 78 9252
TEST_TIME 79 9211
TEST_TIME 80 9173
TEST_TIME 81 9142
TEST_TIME 82 9152
TEST_TIME 83 9120
TEST_TIME 84 9052
TEST_TIME 85 9070
TEST_TIME 86 9034
TEST_TIME 87 9076
TEST_TIME 88 9168
TEST_TIME 89 9152
TEST_TIME 90 9160
TEST_TIME 91 9245
TEST_TIME 92 9251
TEST_TIME 93 9296
TEST_TIME 94 9295
TEST_TIME 95 9363
TEST_TIME 96 9336
TEST_TIME 97 9343
TEST_TIME 98 9327
TEST_TIME 99 9255
TEST_TIME 100 9255
TEST_TIME 101 9243
TEST_TIME 102 9227
TEST_TIME 103 9177
TEST_TIME 104 9128
TEST_TIME 105 9115
TEST_TIME 106 9244
TEST_TIME 107 9221
TEST_TIME 108 9215
TEST_TIME 109 9245
TEST_TIME 110 9203
TEST_TIME 111 9194
TEST_TIME 112 9282
TEST_TIME 113 9361
TEST_TIME 114 9352
TEST_TIME 115 9371
TEST_TIME 116 9396
TEST_TIME 117 9428
TEST_TIME 118 9436
TEST_TIME 119 9369
TEST_TIME 120 9318

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-12-2022 19:40:29 to 18-12-2022 19:40:33.

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 1526487 1526487 0 0.00 0 30216 6 0 0
doc_list_biud 1526486 1526486 0 0.00 0 55206 30 0 0
sp_add_invoice_to_stock 4748 4688 60 1.26 42 187295 18451 0 60
sp_cancel_adding_invoice 6139 6139 0 0.00 4 62280 776 0 0
sp_cancel_client_order 1594 1594 0 0.00 4 11573 406 0 0
sp_cancel_customer_reserve 592542 592542 0 0.00 0 55207 61 0 0
sp_cancel_pay_from_customer 646 646 0 0.00 2 2066 71 0 0
sp_cancel_pay_to_supplier 344 344 0 0.00 2 4012 66 0 0
sp_cancel_supplier_invoice 6790 6787 3 0.04 2 12710 795 0 3
sp_cancel_supplier_order 411 411 0 0.00 7 958906 112956 0 0
sp_cancel_write_off 12181 12181 0 0.00 1 7260 108 0 0
sp_client_order 18751 18751 0 0.00 3 25501 375 0 0
sp_customer_reserve 441307 441307 0 0.00 0 57041 178 0 0
sp_fill_shopping_cart 28837 28837 0 0.00 1 17029 39 0 0
sp_fill_shopping_cart_clo_res 441305 441305 0 0.00 0 22820 15 0 0
sp_get_clo_for_invoice 4678 4678 0 0.00 0 29237 374 0 0
sp_kill_cost_storno 19310 19310 0 0.00 0 13143 58 0 0
sp_kill_qstorno_ret_qs2qd 1204463 1204463 0 0.00 0 55201 33 0 0
sp_kill_qty_storno 633393 633393 0 0.00 0 55206 71 0 0
sp_lock_selected_doc 19039 19039 0 0.00 0 8553 7 0 0
sp_make_cost_storno 25515 25515 0 0.00 0 20062 58 0 0
sp_multiply_rows_for_pdistr 25514 25514 0 0.00 0 18762 29 0 0
sp_multiply_rows_for_qdistr 18751 18751 0 0.00 0 13910 170 0 0
sp_pay_from_customer 6469 6469 0 0.00 4 18784 120 0 0
sp_pay_to_supplier 5306 5306 0 0.00 4 13539 147 0 0
sp_payment_common 11775 11775 0 0.00 1 13505 28 0 0
sp_qd_handle_on_cancel_clo 1594 1594 0 0.00 0 11567 353 0 0
sp_qd_handle_on_invoice_upd_sts 10814 10814 0 0.00 0 30256 341 0 0
sp_qd_handle_on_reserve_upd_sts 9064 9064 0 0.00 0 16973 71 0 0
sp_reserve_write_off 9064 9064 0 0.00 6 16982 196 0 0
sp_supplier_invoice 5009 5009 0 0.00 6 89568 3354 0 0
sp_supplier_order 5077 5077 0 0.00 5 58338 1308 0 0
srv_aggregate_perf_data 73 73 0 0.00 2638 775459 33499 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 992 135077 49808 0 0
srv_find_qd_qs_mism 1073558 1073558 0 0.00 0 30203 5 0 0
srv_make_invnt_saldo 488 488 0 0.00 32 124483 1489 0 0
srv_make_money_saldo 734 734 0 0.00 0 1157 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 189571 421215 322247 0 0
t$perf-abend:sp_add_invoice_to_stock 60 60 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4689 4689 0 0.00 0 11597 188 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 0 7954 140 0 0
t$perf-norm:sp_cancel_client_order 1594 1594 0 0.00 0 11375 23 0 0
t$perf-norm:sp_cancel_customer_reserve 615 615 0 0.00 0 761 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 646 646 0 0.00 0 298 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 344 344 0 0.00 0 679 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 1076 17 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 16775 1662 0 0
t$perf-norm:sp_cancel_write_off 908 908 0 0.00 0 8736 17 0 0
t$perf-norm:sp_client_order 18751 18751 0 0.00 0 13458 21 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 1535 5 0 0
t$perf-norm:sp_pay_from_customer 6469 6469 0 0.00 0 5958 8 0 0
t$perf-norm:sp_pay_to_supplier 5306 5306 0 0.00 0 6617 9 0 0
t$perf-norm:sp_reserve_write_off 9064 9064 0 0.00 0 9157 15 0 0
t$perf-norm:sp_supplier_invoice 5009 5009 0 0.00 0 7797 17 0 0
t$perf-norm:sp_supplier_order 5077 5077 0 0.00 0 6089 16 0 0
t$perf-norm:srv_aggregate_perf_data 73 73 0 0.00 0 44 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 2262 40 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 5952 39 0 0
t$perf-norm:srv_make_money_saldo 734 734 0 0.00 0 1150 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 17 6 0 0
v_add_invoice_to_stock 9470 9350 120 1.26 0 11745 25 0 120
v_all_customers 34090 34090 0 0.00 0 20 1 0 0
v_all_suppliers 28056 28056 0 0.00 0 22 1 0 0
v_all_wares 37502 37502 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 7317 16 0 0
v_cancel_client_order 3188 3188 0 0.00 0 6634 10 0 0
v_cancel_customer_prepayment 1292 1292 0 0.00 0 1896 7 0 0
v_cancel_customer_reserve 1230 1230 0 0.00 0 978 4 0 0
v_cancel_payment_to_supplier 688 688 0 0.00 0 2302 8 0 0
v_cancel_supplier_invoice 824 818 6 0.72 0 709 14 0 6
v_cancel_supplier_order 822 822 0 0.00 0 6387 11 0 0
v_cancel_write_off 1816 1816 0 0.00 0 6383 20 0 0
v_max_id_clo_ord 5077 5077 0 0.00 0 73 1 0 0
v_max_id_clo_res 934 934 0 0.00 0 3046 15 0 0
v_max_id_ord_sup 5009 5009 0 0.00 0 7650 4 0 0
v_max_non_paid_invoice 1364 1364 0 0.00 0 468 3 0 0
v_max_non_paid_realizn 3355 3355 0 0.00 0 5123 5 0 0
v_min_id_clo_ord 5077 5077 0 0.00 0 1903 5 0 0
v_min_id_clo_res 934 934 0 0.00 0 6476 22 0 0
v_min_id_ord_sup 5009 5009 0 0.00 0 5299 10 0 0
v_min_non_paid_invoice 5306 5306 0 0.00 0 13501 21 0 0
v_min_non_paid_realizn 6469 6469 0 0.00 0 10377 20 0 0
v_our_firm 9640 9640 0 0.00 0 22 1 0 0
v_reserve_write_off 18128 18128 0 0.00 0 7322 9 0 0
x_lock_depdocs_on_canc_invoice 12517 12517 0 0.00 0 17618 122 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 18158 419 0 0
x_make_qstorno_1000_1200 5077 5077 0 0.00 1 58325 1235 0 0
x_make_qstorno_1200_2000 5009 5009 0 0.00 2 89558 3209 0 0
x_make_qstorno_2100_3300 441307 441307 0 0.00 0 57039 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2022 19:40:34 to 18-12-2022 19:40:38.

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:37 3 8192000000 8798879696 8810168320 5307904 0.064 2 1 2 0 5407504 56816 42784
17:09:38 25430 8192000000 9908923664 10223566848 996223680 10.842 100 87 174 0 1025087072 6322976 22540416
17:11:09 11962 8192000000 9955535184 10278739968 1038675392 11.252 100 90 178 0 1069947296 8790288 22481616
17:12:26 14483 8192000000 9982347680 10316005376 1065034384 11.505 100 87 172 0 1096751456 10164240 21552832
17:13:51 9051 8192000000 10032932336 10375839744 1114246304 11.973 100 82 164 0 1145283584 11802096 19235184
17:15:09 7043 8192000000 10074835040 10433224704 1163106480 12.432 100 81 158 0 1194530496 13444048 17979968
17:16:21 3533 8192000000 10131341344 10496552960 1215593312 12.921 100 90 176 0 1248527728 12539744 20394672
17:17:26 1401 8192000000 10164752464 10538414080 1252005872 13.257 100 81 155 1 1281963712 12832912 17124928
17:18:30 4039 8192000000 10186920000 10570686464 1275119824 13.468 100 79 155 1 1304799968 13362192 16317952
17:19:35 25783 8192000000 10194443312 10596429824 1298859056 13.685 100 59 117 1 1322394608 10117040 13418512
17:21:03 3734 8192000000 10197129536 10603016192 1306388192 13.753 100 33 66 1 1320715136 7206208 7120736
17:22:07 9708 8192000000 10190019248 10610233344 1312677200 13.810 100 15 31 0 1321512640 5646160 3189280
17:23:21 61445 8192000000 10214404544 10630213632 1318876944 13.867 100 35 71 0 1332839904 6515776 7447184
17:25:23 8103 8192000000 10226409024 10641481728 1328817824 13.956 100 30 60 0 1342596960 7093984 6685152
17:26:31 4575 8192000000 10215289200 10635776000 1326122368 13.932 100 35 70 2 1340785776 6829120 7834288
17:27:37 77853 8192000000 10215775936 10637787136 1316804256 13.848 99 31 61 0 1329852000 5989376 7058368
17:29:57 5704 8192000000 10168297648 10572546048 1236729440 13.116 96 27 54 0 1248410752 4952368 6728944
17:31:03 11976 8192000000 10122865504 10521452544 1176450848 12.557 94 20 38 2 1185982784 5167376 4364560
17:32:19 16048 8192000000 10153418144 10540220416 1207323616 12.844 96 32 62 0 1220306560 5107408 7875536
17:33:36 16158 8192000000 10155536576 10560110592 1263083088 13.358 100 18 35 0 1272444656 5177536 4184032
17:34:52 2977 8192000000 10173394160 10580992000 1274807568 13.466 100 28 57 1 1291472336 10692256 5972512
17:35:57 3460 8192000000 10185065312 10593595392 1289091536 13.596 100 33 64 0 1302462176 5346464 8024176
17:37:07 22014 8192000000 10191002064 10603900928 1301137056 13.706 100 30 61 0 1314196736 5610464 7449216
17:38:29 16943 8192000000 10215100144 10626650112 1318384368 13.862 100 39 77 0 1332121680 5331664 8405648
17:39:56 22212 8192000000 10244615856 10660937728 1340741312 14.064 100 36 68 2 1359606000 11446608 7418080
17:41:23 23053 8192000000 10262122368 10682470400 1362081952 14.256 100 34 68 0 1378987792 8597808 8308032
17:42:52 9633 8192000000 10278331008 10700718080 1375204048 14.374 100 43 86 0 1397320128 11970880 10145200
17:44:07 9576 8192000000 10302025088 10720088064 1386369328 14.473 100 45 84 2 1410871936 14768960 9733648
17:45:26 16804 8192000000 10292436272 10718781440 1382600448 14.440 100 39 78 1 1407726352 15825952 9299952
17:46:49 6674 8192000000 10311202256 10733019136 1388208144 14.490 100 47 94 1 1418855552 19288944 11358464
17:48:00 990 8192000000 10335043776 10753093632 1406211936 14.650 100 76 152 3 1443600176 19277120 18111120
17:49:06 7543 8192000000 10344010016 10767245312 1407734080 14.664 100 68 136 0 1453755200 29445520 16575600
17:50:27 13181 8192000000 10348884608 10775158784 1404479616 14.635 99 56 112 0 1452691600 34248944 13963040
17:51:42 3575 8192000000 10329023744 10743062528 1379648768 14.413 100 63 126 0 1431928384 37146288 15133328
17:52:56 9091 8192000000 10345933296 10754154496 1383744272 14.450 99 79 157 1 1437995920 35349040 18902608
17:54:09 1997 8192000000 10336372304 10748387328 1377883680 14.398 99 68 136 3 1432334704 36986240 17464784
17:55:17 5776 8192000000 10353095248 10766344192 1399339440 14.589 100 68 136 0 1459407248 43387600 16680208
17:56:40 2197 8192000000 10354131888 10758811648 1391181248 14.516 99 77 152 0 1453079408 43231344 18666816
17:57:51 6222 8192000000 10333449600 10756546560 1386213840 14.472 100 48 95 1 1446365632 48050304 12101488
17:59:04 2174 8192000000 10320623936 10734178304 1353350064 14.178 99 55 106 1 1419158608 50881648 14926896
18:00:12 4345 8192000000 10359294592 10778427392 1398419712 14.581 100 54 108 0 1463365792 50835904 14110176
18:01:24 8229 8192000000 10361513488 10781945856 1388967488 14.497 99 52 104 1 1458063904 54622880 14473536
18:02:41 18926 8192000000 10357771184 10780254208 1370420464 14.331 98 42 84 0 1445227600 62042800 12764336
18:04:02 33841 8192000000 10329026112 10762371072 1366996368 14.300 99 28 56 0 1420759616 45635088 8128160
18:05:59 23404 8192000000 10336867248 10776018944 1385036720 14.462 100 28 56 0 1442328736 49022688 8269328
18:07:40 62569 8192000000 10305585504 10741501952 1341425344 14.070 99 19 37 0 1400097792 53123168 5549280
18:09:58 10981 8192000000 10302592640 10740965376 1367809632 14.307 99 29 58 0 1402133472 27203088 7120752
18:12:30 77081 8192000000 10282521744 10730483712 1348280832 14.132 98 23 47 1 1373159856 19605872 5273152
18:14:50 78337 8192000000 10275430368 10721316864 1345794608 14.110 98 30 61 0 1364557424 11773232 6989584
18:17:26 78181 8192000000 10278171008 10731249664 1372779328 14.352 100 21 42 0 1386585408 9507024 4299056
18:19:47 116290 8192000000 10288284080 10746212352 1370398080 14.331 99 25 50 1 1387926272 12339264 5188928
18:22:57 71190 8192000000 10283565440 10731171840 1344778368 14.100 97 16 35 0 1361558192 12993520 3786304
18:25:21 78326 8192000000 10298186912 10742595584 1386377488 14.474 100 29 56 0 1399504720 6306416 6820816
18:27:44 87972 8192000000 10293433680 10770145280 1397568608 14.573 100 32 64 0 1418705888 14146272 6991008
18:30:26 36033 8192000000 10295744576 10752757760 1396661040 14.565 100 21 42 0 1412038800 10561808 4815952
18:32:04 35968 8192000000 10299630768 10763706368 1403124176 14.623 100 9 16 0 1415833248 10640672 2068400
18:33:44 56289 8192000000 10271774496 10740178944 1370802832 14.334 99 8 16 1 1385351104 12760512 1787760
18:35:53 84328 8192000000 10267504608 10736451584 1360124112 14.238 98 20 40 0 1373987488 9220512 4642864
18:38:17 64259 8192000000 10280035232 10748059648 1370410288 14.331 99 9 18 0 1383759904 11486464 1863152
18:40:22 106410 8192000000 10295847776 10761089024 1347656688 14.126 96 12 24 0 1362367968 11994592 2716688
18:43:08 40737 8192000000 10302334624 10752872448 1359376384 14.232 97 23 46 0 1376426736 12405824 4644528
18:44:50 85021 8192000000 10290568688 10744201216 1336433056 14.025 96 22 44 0 1352594368 10918528 5242784
18:47:16 76549 8192000000 10294354944 10752585728 1371575008 14.341 99 17 34 0 1392496832 17008928 3912896
18:49:46 124509 8192000000 10285171136 10735161344 1315806864 13.839 95 4 8 0 1326785680 10219696 759120
18:52:55 86351 8192000000 10287871952 10732601344 1352218592 14.167 98 45 86 0 1375764928 13572752 9973584
18:55:27 19163 8192000000 10264540064 10700070912 1346842688 14.119 100 42 83 1 1367061936 11651088 8568160
18:56:47 44549 8192000000 10232222752 10682200064 1306754640 13.757 96 18 36 0 1318121680 7383680 3983360
18:58:44 52000 8192000000 10248586944 10700873728 1336687104 14.028 99 17 34 1 1350779744 10132176 3960464
19:00:39 45002 8192000000 10256064640 10704494592 1340752336 14.064 99 13 25 0 1355710592 12140528 2817728
19:02:24 64141 8192000000 10268071712 10720886784 1347127328 14.122 99 15 28 0 1357978064 7515712 3335024
19:04:28 33425 8192000000 10263965648 10697195520 1341757520 14.073 100 27 49 2 1360301856 12242704 6301632
19:06:04 81046 8192000000 10254512704 10709168128 1325192048 13.924 98 12 25 0 1337172128 9522048 2458032
19:08:25 87225 8192000000 10253258160 10707353600 1305560240 13.746 96 8 16 0 1320371584 12992544 1818800
19:11:15 98413 8192000000 10282978304 10741305344 1326832176 13.939 97 10 20 0 1347728160 18746768 2149216
19:13:54 56005 8192000000 10291095824 10732007424 1367601200 14.306 99 10 18 0 1379741456 9581984 2558272
19:15:50 135077 8192000000 10284941952 10741678080 1347690656 14.127 97 24 48 0 1368513536 15323328 5499552
19:19:06 25493 8192000000 10293293200 10755461120 1382294768 14.437 100 13 26 0 1405585648 20420352 2870528
19:20:32 56801 8192000000 10280232016 10743902208 1364383248 14.277 98 8 16 0 1379670288 13264224 2022816
19:22:30 69062 8192000000 10302663920 10759061504 1385963984 14.470 99 17 34 0 1404349472 14032656 4352832
19:24:41 110544 8192000000 10280604768 10728087552 1320300848 13.879 95 12 24 0 1335131024 12113984 2716192
19:27:33 73593 8192000000 10319716464 10762194944 1376303488 14.383 99 18 36 1 1391446000 10911296 4231216
19:29:56 83779 8192000000 10314234672 10774802432 1380060176 14.417 99 18 36 0 1403820272 19280880 4479216
19:32:46 67800 8192000000 10312590080 10755919872 1362952640 14.264 98 16 32 1 1378914560 12420320 3541600
19:34:58 131300 8192000000 10296608656 10768453632 1366811888 14.298 98 14 28 0 1388089776 18111776 3166112

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-12-2022 19:40:40 to 18-12-2022 19:40:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 60 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-12-2022 19:40:56 to 18-12-2022 19:40:58.

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          226376
MON$OLDEST_ACTIVE               227426
MON$OLDEST_SNAPSHOT             227426
MON$NEXT_TRANSACTION            227426
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                       2045600
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:821: 
:822: mscs-test-161.budzdorov.ru    Sun Dec 18 19:38:38 2022
:823:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:824: 
:825: 
:826: mscs-test-161.budzdorov.ru    Sun Dec 18 19:38:38 2022
:827:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:828: 
--- end of diff output ---

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

18.12.22 19:40:59. 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-12-18 19:41:01 No severe PSQL-related problems occured
18.12.22 19:41:03. End of report.