Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.09.2022 19:38
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 15612236 kB
MemAvailable 30567672 kB
Buffers 0 kB
Cached 16097724 kB
SwapCached 2064 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-09-29 19:38:32
DTS_END                         2022-09-29 19:38:32.9730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37518
TRN_ID                          251415
ATT_ID                          684
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: 9152.10 21441 1098252
customer order: creation 187.82 334 22538
customer order: refuse 15.73 392 1887
order to supplier: creation 49.79 1259 5975
order to supplier: removal 3.23 84367 387
invoice (draft): creation 48.51 3396 5821
invoice (draft): removal 49.73 740 5968
invoice accept: apply 44.37 18534 5324
invoice accept: cancel 45.33 722 5440
customer reserve: creation 4012.91 190 481549
customer reserve: removal 4388.06 48 526567
realization accept: apply 90.93 165 10912
realization accept: cancel 74.68 99 8961
payment from customer: creation 64.26 99 7711
payment from customer: removal 6.26 83 751
payment to supplier: creation 53.63 107 6436
payment to supplier: removal 3.53 61 423
service: total inventory turnovers 4.91 1196 589
service: total monetary turnovers 7.53 31 903
service: aggregate perf. data 0.88 19267 106
service: refresh index statistics 0.03 297728 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-09-2022 19:39:20 to 29-09-2022 19:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7815
TEST_TIME 2 4778
TEST_TIME 3 5036
TEST_TIME 4 6250
TEST_TIME 5 6403
TEST_TIME 6 6530
TEST_TIME 7 7353
TEST_TIME 8 7453
TEST_TIME 9 7004
TEST_TIME 10 7022
TEST_TIME 11 6953
TEST_TIME 12 7198
TEST_TIME 13 7310
TEST_TIME 14 7313
TEST_TIME 15 7371
TEST_TIME 16 7462
TEST_TIME 17 7887
TEST_TIME 18 7949
TEST_TIME 19 7923
TEST_TIME 20 7759
TEST_TIME 21 7740
TEST_TIME 22 8090
TEST_TIME 23 8471
TEST_TIME 24 8576
TEST_TIME 25 8719
TEST_TIME 26 8840
TEST_TIME 27 9065
TEST_TIME 28 9706
TEST_TIME 29 9708
TEST_TIME 30 9761
TEST_TIME 31 10045
TEST_TIME 32 10262
TEST_TIME 33 10043
TEST_TIME 34 9834
TEST_TIME 35 9954
TEST_TIME 36 9754
TEST_TIME 37 9707
TEST_TIME 38 9631
TEST_TIME 39 9663
TEST_TIME 40 9598
TEST_TIME 41 9543
TEST_TIME 42 9455
TEST_TIME 43 9427
TEST_TIME 44 9353
TEST_TIME 45 9254
TEST_TIME 46 9165
TEST_TIME 47 9228
TEST_TIME 48 9165
TEST_TIME 49 9073
TEST_TIME 50 9102
TEST_TIME 51 9221
TEST_TIME 52 9274
TEST_TIME 53 9260
TEST_TIME 54 9279
TEST_TIME 55 9301
TEST_TIME 56 9316
TEST_TIME 57 9405
TEST_TIME 58 9456
TEST_TIME 59 9475
TEST_TIME 60 9449
TEST_TIME 61 9557
TEST_TIME 62 9525
TEST_TIME 63 9380
TEST_TIME 64 9337
TEST_TIME 65 9336
TEST_TIME 66 9271
TEST_TIME 67 9233
TEST_TIME 68 9159
TEST_TIME 69 9137
TEST_TIME 70 9084
TEST_TIME 71 9081
TEST_TIME 72 9036
TEST_TIME 73 9048
TEST_TIME 74 9069
TEST_TIME 75 9077
TEST_TIME 76 9102
TEST_TIME 77 9067
TEST_TIME 78 9112
TEST_TIME 79 9115
TEST_TIME 80 9116
TEST_TIME 81 9191
TEST_TIME 82 9252
TEST_TIME 83 9236
TEST_TIME 84 9160
TEST_TIME 85 9104
TEST_TIME 86 9079
TEST_TIME 87 9069
TEST_TIME 88 9022
TEST_TIME 89 8987
TEST_TIME 90 8980
TEST_TIME 91 8958
TEST_TIME 92 9048
TEST_TIME 93 9057
TEST_TIME 94 9066
TEST_TIME 95 9081
TEST_TIME 96 9117
TEST_TIME 97 9108
TEST_TIME 98 9128
TEST_TIME 99 9127
TEST_TIME 100 9179
TEST_TIME 101 9172
TEST_TIME 102 9245
TEST_TIME 103 9263
TEST_TIME 104 9313
TEST_TIME 105 9229
TEST_TIME 106 9222
TEST_TIME 107 9174
TEST_TIME 108 9120
TEST_TIME 109 9101
TEST_TIME 110 9111
TEST_TIME 111 9139
TEST_TIME 112 9118
TEST_TIME 113 9076
TEST_TIME 114 9123
TEST_TIME 115 9173
TEST_TIME 116 9158
TEST_TIME 117 9136
TEST_TIME 118 9124
TEST_TIME 119 9177
TEST_TIME 120 9156

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-09-2022 19:39:24 to 29-09-2022 19:39:27.

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 1543351 1543351 0 0.00 0 36641 7 0 0
doc_list_biud 1543351 1543351 0 0.00 0 30574 22 0 0
sp_add_invoice_to_stock 5434 5324 110 2.02 18 222208 18535 0 110
sp_cancel_adding_invoice 5440 5440 0 0.00 3 68810 723 0 0
sp_cancel_client_order 1887 1887 0 0.00 4 13075 392 0 0
sp_cancel_customer_reserve 526567 526567 0 0.00 0 22834 48 0 0
sp_cancel_pay_from_customer 751 751 0 0.00 2 5287 84 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 1649 62 0 0
sp_cancel_supplier_invoice 5983 5968 15 0.25 2 10788 740 0 15
sp_cancel_supplier_order 387 387 0 0.00 9 998335 84367 0 0
sp_cancel_write_off 8961 8961 0 0.00 1 22725 100 0 0
sp_client_order 22538 22538 0 0.00 3 26823 334 0 0
sp_customer_reserve 481549 481549 0 0.00 0 41341 190 0 0
sp_fill_shopping_cart 34311 34311 0 0.00 1 17667 39 0 0
sp_fill_shopping_cart_clo_res 481533 481533 0 0.00 0 13249 13 0 0
sp_get_clo_for_invoice 5308 5308 0 0.00 0 20471 265 0 0
sp_kill_cost_storno 15575 15575 0 0.00 0 14312 43 0 0
sp_kill_qstorno_ret_qs2qd 1068450 1068450 0 0.00 0 22962 27 0 0
sp_kill_qty_storno 565430 565430 0 0.00 0 30572 58 0 0
sp_lock_selected_doc 22297 22297 0 0.00 0 6657 4 0 0
sp_make_cost_storno 30367 30367 0 0.00 0 11400 46 0 0
sp_multiply_rows_for_pdistr 30367 30367 0 0.00 0 7274 22 0 0
sp_multiply_rows_for_qdistr 22538 22538 0 0.00 0 10479 151 0 0
sp_pay_from_customer 7711 7711 0 0.00 4 12246 99 0 0
sp_pay_to_supplier 6436 6436 0 0.00 4 10879 107 0 0
sp_payment_common 14147 14147 0 0.00 1 10057 26 0 0
sp_qd_handle_on_cancel_clo 1887 1887 0 0.00 0 13067 362 0 0
sp_qd_handle_on_invoice_upd_sts 10748 10748 0 0.00 0 30568 282 0 0
sp_qd_handle_on_reserve_upd_sts 10912 10912 0 0.00 0 15999 54 0 0
sp_reserve_write_off 10912 10912 0 0.00 6 16014 166 0 0
sp_supplier_invoice 5821 5821 0 0.00 5 102438 3396 0 0
sp_supplier_order 5975 5975 0 0.00 4 38867 1260 0 0
srv_aggregate_perf_data 106 106 0 0.00 2566 472083 19267 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 908 151798 41308 0 0
srv_find_qd_qs_mism 1046741 1046741 0 0.00 0 36640 7 0 0
srv_make_invnt_saldo 589 589 0 0.00 40 98034 1197 0 0
srv_make_money_saldo 903 903 0 0.00 1 4438 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 109965 384418 297728 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 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5324 5324 0 0.00 0 31223 157 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 5826 101 0 0
t$perf-norm:sp_cancel_client_order 1887 1887 0 0.00 0 2965 13 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 400 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 751 751 0 0.00 0 1292 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 1888 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 1701 15 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 27674 1564 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 532 3 0 0
t$perf-norm:sp_client_order 22538 22538 0 0.00 0 23678 14 0 0
t$perf-norm:sp_customer_reserve 1073 1073 0 0.00 0 2737 8 0 0
t$perf-norm:sp_pay_from_customer 7711 7711 0 0.00 0 10160 11 0 0
t$perf-norm:sp_pay_to_supplier 6436 6436 0 0.00 0 11229 11 0 0
t$perf-norm:sp_reserve_write_off 10912 10912 0 0.00 0 2309 8 0 0
t$perf-norm:sp_supplier_invoice 5821 5821 0 0.00 0 22143 19 0 0
t$perf-norm:sp_supplier_order 5977 5977 0 0.00 0 4188 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 379 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 1214 23 0 0
t$perf-norm:srv_make_money_saldo 903 903 0 0.00 0 10335 36 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 11 4 0 0
v_add_invoice_to_stock 10836 10616 220 2.03 0 8421 20 0 220
v_all_customers 40356 40356 0 0.00 0 17 1 0 0
v_all_suppliers 32614 32614 0 0.00 0 99 1 0 0
v_all_wares 45074 45074 0 0.00 0 68 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 965 8 0 0
v_cancel_client_order 3774 3774 0 0.00 0 3255 8 0 0
v_cancel_customer_prepayment 1502 1502 0 0.00 0 2941 12 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 1934 8 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 188 3 0 0
v_cancel_supplier_invoice 896 866 30 3.34 0 3566 18 0 30
v_cancel_supplier_order 774 774 0 0.00 0 2398 7 0 0
v_cancel_write_off 2082 2082 0 0.00 0 2547 17 0 0
v_max_id_clo_ord 5972 5972 0 0.00 0 1399 2 0 0
v_max_id_clo_res 1073 1073 0 0.00 0 1479 13 0 0
v_max_id_ord_sup 5802 5802 0 0.00 0 5086 3 0 0
v_max_non_paid_invoice 1903 1903 0 0.00 0 218 2 0 0
v_max_non_paid_realizn 4401 4401 0 0.00 0 9559 6 0 0
v_min_id_clo_ord 5972 5972 0 0.00 0 7596 7 0 0
v_min_id_clo_res 1073 1073 0 0.00 0 6979 10 0 0
v_min_id_ord_sup 5802 5802 0 0.00 0 4947 9 0 0
v_min_non_paid_invoice 6436 6436 0 0.00 0 7147 17 0 0
v_min_non_paid_realizn 7711 7711 0 0.00 0 6470 20 0 0
v_our_firm 11338 11338 0 0.00 0 21 1 0 0
v_reserve_write_off 21824 21824 0 0.00 0 2965 7 0 0
x_lock_depdocs_on_canc_invoice 10975 10975 0 0.00 0 20622 107 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 11103 414 0 0
x_make_qstorno_1000_1200 5975 5975 0 0.00 1 38838 1183 0 0
x_make_qstorno_1200_2000 5821 5821 0 0.00 2 102363 3245 0 0
x_make_qstorno_2100_3300 481549 481549 0 0.00 0 39546 175 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 29-09-2022 19:39:28 to 29-09-2022 19:39:33.

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:36 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:38 25347 8192000000 9898398992 10211340288 981193552 10.696 100 84 168 0 1009561360 6008560 22359248
17:11:07 19193 8192000000 9956298688 10279305216 1036683296 11.233 100 93 186 0 1068521184 8186016 23651872
17:12:31 13694 8192000000 9981097008 10312216576 1062558864 11.481 100 87 174 0 1094007200 9916896 21531440
17:13:48 9872 8192000000 10016532464 10361487360 1096947520 11.809 100 76 152 0 1125631344 10251616 18432208
17:15:02 8484 8192000000 10058905232 10415091712 1149528288 12.305 100 73 146 0 1178103504 11909248 16665968
17:16:18 4310 8192000000 10115290048 10481139712 1203127056 12.805 100 84 167 0 1235421984 13548096 18746832
17:17:25 2180 8192000000 10154267616 10525446144 1237488112 13.123 100 87 172 0 1271008016 14600000 18919904
17:18:28 1663 8192000000 10178425936 10557804544 1272589280 13.445 100 87 175 2 1309933504 18837424 18506800
17:19:33 1062 8192000000 10198870128 10584764416 1286758128 13.575 100 90 178 0 1320552368 13463152 20331088
17:20:34 17618 8192000000 10206206656 10604404736 1303332528 13.726 100 67 134 2 1332431136 13457200 15641408
17:22:13 4542 8192000000 10209141984 10620735488 1313426448 13.817 100 40 80 0 1328892608 6494848 8971312
17:23:22 6333 8192000000 10214893664 10630680576 1326893088 13.939 100 40 80 0 1344589072 9026704 8669280
17:24:31 17575 8192000000 10215313936 10635603968 1331712864 13.983 100 22 44 0 1342929088 5292496 5923728
17:25:50 14155 8192000000 10216659568 10644213760 1334057168 14.004 100 24 48 0 1345818944 6413952 5347824
17:27:05 2599 8192000000 10237254976 10653388800 1339319456 14.051 100 34 68 0 1354664656 8081920 7263280
17:28:09 11355 8192000000 10218931200 10639831040 1312313520 13.807 98 17 34 2 1321325488 5141136 3870832
17:29:22 12952 8192000000 10213288896 10623791104 1298765136 13.684 98 23 46 1 1308776944 4774240 5237568
17:31:14 10030 8192000000 10166022656 10580258816 1247376464 13.214 96 26 52 2 1259342320 6194304 5771552
17:32:25 5071 8192000000 10156906048 10558001152 1262605232 13.354 100 34 68 0 1278273568 7868992 7799344
17:33:31 5352 8192000000 10158884464 10552545280 1256850192 13.301 100 37 72 0 1270949776 5152224 8947360
17:34:38 61629 8192000000 10173250304 10580664320 1275013248 13.467 100 27 50 1 1290575040 9304560 6257232
17:36:41 8869 8192000000 10189012768 10595024896 1294221904 13.643 100 28 52 0 1306197984 5207120 6768960
17:37:50 8220 8192000000 10193587648 10608963584 1299069968 13.687 100 19 38 0 1308531664 5083488 4378208
17:39:03 5709 8192000000 10233322240 10641330176 1327828736 13.948 100 54 105 1 1348097664 7889312 12379616
17:40:11 44751 8192000000 10256040960 10670628864 1347014416 14.121 100 60 119 0 1373705920 12267552 14423952
17:42:03 8330 8192000000 10268077824 10683764736 1355708272 14.199 100 45 89 0 1375444512 9835104 9901136
17:43:18 10639 8192000000 10282543088 10696704000 1361311168 14.249 100 54 109 0 1386388896 13356896 11720832
17:44:32 2414 8192000000 10299571216 10713509888 1375121488 14.373 100 70 140 0 1406798288 16403488 15273312
17:45:50 14134 8192000000 10283361296 10713354240 1381967328 14.434 100 38 76 1 1402593440 11846912 8779200
17:47:16 9551 8192000000 10299709120 10723823616 1388689920 14.494 100 55 110 0 1415693296 14577184 12426192
17:48:41 11181 8192000000 10298346560 10724093952 1378145840 14.400 99 43 84 1 1402823248 14507360 10170048
17:49:56 13552 8192000000 10293343968 10713927680 1377088288 14.391 100 57 113 1 1407503632 17041584 13373760
17:51:14 907 8192000000 10303268768 10709504000 1367923856 14.308 100 81 159 1 1403997648 17030016 19043776
17:52:19 2238 8192000000 10297212112 10709831680 1366527872 14.296 100 84 165 2 1411203664 23860576 20815216
17:53:26 9597 8192000000 10312485568 10726035456 1376571952 14.386 100 65 131 1 1419488016 26683616 16232448
17:54:37 1339 8192000000 10306293808 10717413376 1357568880 14.216 100 73 144 1 1403127008 27537536 18020592
17:55:44 6659 8192000000 10295736800 10704629760 1358601824 14.225 100 69 137 0 1398123200 23156176 16365200
17:57:02 4055 8192000000 10302848416 10715459584 1365822656 14.290 100 56 112 2 1407123760 27188928 14112176
17:58:13 12327 8192000000 10295096544 10700820480 1346890592 14.119 98 58 111 1 1386976864 26167904 13918368
17:59:34 2483 8192000000 10324914688 10733731840 1379464752 14.412 100 64 128 2 1429723216 33892064 16366400
18:00:41 3463 8192000000 10321756000 10738417664 1374616128 14.368 100 57 113 0 1430919280 40968576 15334576
18:01:51 12270 8192000000 10293581488 10711732224 1352061200 14.166 99 38 75 0 1390982400 29112624 9808576
18:03:14 13563 8192000000 10296522224 10723332096 1351178800 14.158 99 29 56 0 1390345920 31690528 7476592
18:04:31 16230 8192000000 10300838976 10730803200 1358577968 14.225 100 22 44 1 1404172928 39480432 6114528
18:05:53 16157 8192000000 10290296864 10728312832 1348374192 14.133 100 25 49 0 1397524368 42650464 6499712
18:07:12 49717 8192000000 10285109760 10716872704 1334685552 14.009 98 15 30 0 1361009152 22438688 3884912
18:09:03 37827 8192000000 10306911888 10748739584 1347015920 14.121 98 17 35 0 1375475888 24041936 4418032
18:10:53 48706 8192000000 10293076528 10728017920 1369042416 14.318 99 29 58 2 1384488656 9486224 5960016
18:12:50 44399 8192000000 10286490320 10737360896 1379231408 14.410 100 42 84 1 1404131200 13970416 10929376
18:14:37 36266 8192000000 10280897776 10725498880 1367708480 14.307 99 34 67 1 1383690272 9185952 6795840
18:16:23 19040 8192000000 10310559472 10749698048 1394361424 14.545 100 59 116 1 1417143552 9787616 12994512
18:17:57 16584 8192000000 10280717216 10743726080 1370594256 14.332 98 13 27 1 1381375888 7377216 3404416
18:19:14 27322 8192000000 10297818080 10756714496 1386668400 14.476 99 7 13 1 1398502528 10556528 1277600
18:20:42 38215 8192000000 10300352144 10759389184 1405011856 14.640 100 25 50 0 1417173568 6615328 5546384
18:22:22 32538 8192000000 10304488640 10763546624 1401392256 14.607 99 17 34 0 1420172128 14554048 4225824
18:23:55 104580 8192000000 10291213328 10769334272 1404220928 14.633 100 27 53 0 1419579360 9703632 5654800
18:27:14 48477 8192000000 10309296880 10771759104 1395500928 14.555 99 17 34 0 1413993344 14747936 3744480
18:29:58 67192 8192000000 10306526656 10769076224 1382236592 14.437 98 18 34 2 1397310112 11188336 3885184
18:32:09 23332 8192000000 10296356016 10744315904 1379838416 14.415 99 32 64 0 1396876384 10188048 6849920
18:33:36 15188 8192000000 10285348208 10731020288 1367350352 14.303 99 39 78 0 1386616064 10960336 8305376
18:34:51 12589 8192000000 10288749504 10733821952 1363480736 14.269 99 48 94 1 1388333216 14216720 10635760
18:36:18 42217 8192000000 10265219600 10722467840 1332154576 13.987 97 12 24 0 1349693008 14376336 3162096
18:38:01 28466 8192000000 10285301424 10721976320 1339063392 14.049 97 36 72 0 1356277264 9351840 7862032
18:39:31 134201 8192000000 10279665120 10731765760 1349574144 14.144 98 11 21 1 1368764256 17321360 1868752
18:42:48 25879 8192000000 10264482512 10694356992 1327846576 13.948 97 31 61 1 1343551840 8207680 7497584
18:44:15 61052 8192000000 10266940032 10725998592 1367756208 14.307 100 13 25 0 1382829520 12300528 2772784
18:46:16 22082 8192000000 10272697872 10724003840 1372042656 14.345 100 14 29 0 1382957968 7860816 3054496
18:47:39 69410 8192000000 10249970432 10708492288 1307619136 13.764 96 9 18 1 1323059696 13282032 2158528
18:49:49 60766 8192000000 10250062448 10698006528 1313062560 13.814 97 13 26 0 1328223536 11561264 3599712
18:51:51 31617 8192000000 10277036288 10710446080 1346877632 14.119 99 36 74 0 1363865936 8953072 8035232
18:53:36 47368 8192000000 10239110560 10683228160 1297389248 13.671 96 29 58 0 1313923744 9839552 6694944
18:55:38 76923 8192000000 10261805056 10711638016 1344834720 14.101 99 22 44 0 1362106880 12504672 4767488
18:57:56 102718 8192000000 10278921664 10709954560 1323572208 13.909 97 19 38 0 1345362656 17289168 4501280
19:00:40 70359 8192000000 10278661648 10721710080 1346534432 14.116 98 17 34 1 1360403456 10234064 3634960
19:02:52 86580 8192000000 10284691600 10730098688 1355214736 14.194 98 39 78 0 1375567120 11445728 8906656
19:05:23 39340 8192000000 10263134512 10726322176 1365545056 14.287 99 8 16 1 1374630592 7479616 1605920
19:07:08 63905 8192000000 10277673984 10737635328 1344451328 14.098 97 5 10 0 1356282656 10897136 934192
19:09:12 75194 8192000000 10312264592 10768691200 1386110768 14.471 99 13 26 0 1403128240 14207824 2809648
19:11:36 69839 8192000000 10287190288 10739802112 1329775760 13.965 95 13 26 0 1341941712 9069904 3096048
19:13:47 97622 8192000000 10305006208 10762694656 1368844000 14.317 98 24 48 0 1386090144 11558752 5687392
19:16:29 42526 8192000000 10280119456 10719141888 1298551712 13.682 93 27 48 0 1310517168 7145040 4820416
19:18:12 89567 8192000000 10294533920 10746540032 1350455248 14.152 98 16 32 0 1366194816 11774096 3965472
19:20:42 151798 8192000000 10294544416 10764419072 1348566416 14.135 97 8 16 1 1365027200 15345024 1115760
19:24:16 67499 8192000000 10271166560 10735456256 1336535088 14.026 97 19 38 2 1355313296 13995472 4782736
19:26:24 65823 8192000000 10288321072 10759831552 1363937664 14.273 99 7 14 0 1389086752 23253072 1896016
19:28:30 80188 8192000000 10280517696 10744496128 1327071472 13.941 97 8 16 0 1348902816 20153888 1677456
19:30:52 44962 8192000000 10270779840 10709729280 1306621376 13.755 95 27 54 1 1325747168 12215344 6910448
19:33:03 79050 8192000000 10266514352 10718879744 1341049936 14.067 99 6 12 0 1355978400 13622800 1305664
19:35:22 63340 8192000000 10274225840 10705600512 1295096224 13.651 95 25 50 1 1310819408 10106016 5617168
19:37:37 33618 8192000000 10285482832 10745131008 1361649952 14.252 100 16 32 0 1385179520 20133632 3395936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-09-2022 19:39:35 to 29-09-2022 19:39:50.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-09-2022 19:39:51 to 29-09-2022 19:39:53.

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          249011
MON$OLDEST_ACTIVE               251528
MON$OLDEST_SNAPSHOT             251528
MON$NEXT_TRANSACTION            251528
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                       2027552
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:235: 
:236: mscs-test-161.budzdorov.ru    Thu Sep 29 19:38:34 2022
:237:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:238: 
:239: 
:240: mscs-test-161.budzdorov.ru    Thu Sep 29 19:38:36 2022
:241:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:242: 
:243: 
:244: mscs-test-161.budzdorov.ru    Thu Sep 29 19:38:40 2022
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:246: 
--- end of diff output ---

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

29.09.22 19:39:54. 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-09-29 19:39:57 No severe PSQL-related problems occured
29.09.22 19:39:58. End of report.