Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2021 22: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15884724 kB
MemAvailable 30636228 kB
Buffers 0 kB
Cached 15796944 kB
SwapCached 3768 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.8
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 2021-10-16 22:37:57
DTS_END                         2021-10-16 22:37:57.7870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46918
TRN_ID                          229823
ATT_ID                          697
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: 9395.63 20300 1127476
customer order: creation 168.26 381 20191
customer order: refuse 14.69 331 1763
order to supplier: creation 44.72 1337 5366
order to supplier: removal 3.65 99827 438
invoice (draft): creation 44.83 3385 5379
invoice (draft): removal 53.64 756 6437
invoice accept: apply 41.08 19059 4929
invoice accept: cancel 48.58 803 5829
customer reserve: creation 3841.06 187 460927
customer reserve: removal 4843.35 59 581202
realization accept: apply 80.39 196 9647
realization accept: cancel 85.20 111 10224
payment from customer: creation 57.75 120 6930
payment from customer: removal 5.93 91 711
payment to supplier: creation 47.36 142 5683
payment to supplier: removal 3.29 75 395
service: total inventory turnovers 4.94 1021 593
service: total monetary turnovers 6.13 32 736
service: aggregate perf. data 0.77 23426 92
service: refresh index statistics 0.03 254670 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-10-2021 22:38:45 to 16-10-2021 22:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 770
TEST_TIME 2 1946
TEST_TIME 3 2583
TEST_TIME 4 3819
TEST_TIME 5 4072
TEST_TIME 6 4243
TEST_TIME 7 4645
TEST_TIME 8 4801
TEST_TIME 9 4833
TEST_TIME 10 4927
TEST_TIME 11 5009
TEST_TIME 12 4934
TEST_TIME 13 5078
TEST_TIME 14 5279
TEST_TIME 15 5446
TEST_TIME 16 5541
TEST_TIME 17 5735
TEST_TIME 18 6002
TEST_TIME 19 6153
TEST_TIME 20 6574
TEST_TIME 21 6670
TEST_TIME 22 6721
TEST_TIME 23 7128
TEST_TIME 24 7280
TEST_TIME 25 7956
TEST_TIME 26 8137
TEST_TIME 27 8102
TEST_TIME 28 8270
TEST_TIME 29 8415
TEST_TIME 30 8482
TEST_TIME 31 9175
TEST_TIME 32 9722
TEST_TIME 33 9651
TEST_TIME 34 9601
TEST_TIME 35 9741
TEST_TIME 36 9820
TEST_TIME 37 9759
TEST_TIME 38 9738
TEST_TIME 39 9681
TEST_TIME 40 9450
TEST_TIME 41 9394
TEST_TIME 42 9452
TEST_TIME 43 9340
TEST_TIME 44 9350
TEST_TIME 45 9326
TEST_TIME 46 9301
TEST_TIME 47 9338
TEST_TIME 48 9423
TEST_TIME 49 9334
TEST_TIME 50 9280
TEST_TIME 51 9261
TEST_TIME 52 9182
TEST_TIME 53 9190
TEST_TIME 54 9285
TEST_TIME 55 9213
TEST_TIME 56 9214
TEST_TIME 57 9173
TEST_TIME 58 9153
TEST_TIME 59 9279
TEST_TIME 60 9253
TEST_TIME 61 9233
TEST_TIME 62 9193
TEST_TIME 63 9457
TEST_TIME 64 9420
TEST_TIME 65 9421
TEST_TIME 66 9465
TEST_TIME 67 9447
TEST_TIME 68 9470
TEST_TIME 69 9546
TEST_TIME 70 9515
TEST_TIME 71 9602
TEST_TIME 72 9589
TEST_TIME 73 9492
TEST_TIME 74 9498
TEST_TIME 75 9408
TEST_TIME 76 9357
TEST_TIME 77 9478
TEST_TIME 78 9412
TEST_TIME 79 9377
TEST_TIME 80 9338
TEST_TIME 81 9307
TEST_TIME 82 9224
TEST_TIME 83 9216
TEST_TIME 84 9278
TEST_TIME 85 9276
TEST_TIME 86 9318
TEST_TIME 87 9337
TEST_TIME 88 9343
TEST_TIME 89 9376
TEST_TIME 90 9327
TEST_TIME 91 9447
TEST_TIME 92 9475
TEST_TIME 93 9509
TEST_TIME 94 9529
TEST_TIME 95 9481
TEST_TIME 96 9486
TEST_TIME 97 9530
TEST_TIME 98 9512
TEST_TIME 99 9470
TEST_TIME 100 9441
TEST_TIME 101 9398
TEST_TIME 102 9384
TEST_TIME 103 9414
TEST_TIME 104 9374
TEST_TIME 105 9366
TEST_TIME 106 9339
TEST_TIME 107 9283
TEST_TIME 108 9305
TEST_TIME 109 9290
TEST_TIME 110 9307
TEST_TIME 111 9292
TEST_TIME 112 9272
TEST_TIME 113 9308
TEST_TIME 114 9351
TEST_TIME 115 9355
TEST_TIME 116 9366
TEST_TIME 117 9406
TEST_TIME 118 9390
TEST_TIME 119 9404
TEST_TIME 120 9399

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-10-2021 22:38:49 to 16-10-2021 22:38:52.

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 1554543 1554543 0 0.00 0 26090 6 0 0
doc_list_biud 1554543 1554543 0 0.00 0 40880 28 0 0
sp_add_invoice_to_stock 5003 4929 74 1.47 21 224229 19059 0 74
sp_cancel_adding_invoice 5829 5829 0 0.00 4 81338 803 0 0
sp_cancel_client_order 1763 1763 0 0.00 4 8868 332 0 0
sp_cancel_customer_reserve 581202 581202 0 0.00 1 26303 60 0 0
sp_cancel_pay_from_customer 711 711 0 0.00 2 4875 91 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 2503 76 0 0
sp_cancel_supplier_invoice 6440 6437 3 0.04 2 17150 756 0 3
sp_cancel_supplier_order 438 438 0 0.00 6 1065513 99827 0 0
sp_cancel_write_off 10224 10224 0 0.00 1 7439 112 0 0
sp_client_order 20191 20191 0 0.00 4 25543 381 0 0
sp_customer_reserve 460927 460927 0 0.00 0 130313 187 0 0
sp_fill_shopping_cart 30927 30927 0 0.00 1 25052 34 0 0
sp_fill_shopping_cart_clo_res 460925 460925 0 0.00 0 19477 12 0 0
sp_get_clo_for_invoice 4925 4925 0 0.00 0 17880 290 0 0
sp_kill_cost_storno 17159 17159 0 0.00 0 9011 59 0 0
sp_kill_qstorno_ret_qs2qd 1179503 1179503 0 0.00 0 26282 32 0 0
sp_kill_qty_storno 620465 620465 0 0.00 0 40879 70 0 0
sp_lock_selected_doc 20245 20245 0 0.00 0 7637 5 0 0
sp_make_cost_storno 27185 27185 0 0.00 0 14190 56 0 0
sp_multiply_rows_for_pdistr 27185 27185 0 0.00 0 26086 31 0 0
sp_multiply_rows_for_qdistr 20191 20191 0 0.00 0 18093 164 0 0
sp_pay_from_customer 6930 6930 0 0.00 4 26098 121 0 0
sp_pay_to_supplier 5683 5683 0 0.00 5 19532 142 0 0
sp_payment_common 12613 12613 0 0.00 1 14456 30 0 0
sp_qd_handle_on_cancel_clo 1763 1763 0 0.00 0 8854 300 0 0
sp_qd_handle_on_invoice_upd_sts 10754 10754 0 0.00 0 40878 353 0 0
sp_qd_handle_on_reserve_upd_sts 9647 9647 0 0.00 0 18228 63 0 0
sp_reserve_write_off 9647 9647 0 0.00 6 20036 196 0 0
sp_supplier_invoice 5379 5379 0 0.00 6 108030 3385 0 0
sp_supplier_order 5366 5366 0 0.00 5 58269 1337 0 0
srv_aggregate_perf_data 92 92 0 0.00 2158 196105 23426 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1433 140130 43907 0 0
srv_find_qd_qs_mism 1080649 1080649 0 0.00 0 13970 5 0 0
srv_make_invnt_saldo 593 593 0 0.00 37 23091 1021 0 0
srv_make_money_saldo 736 736 0 0.00 1 2167 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 187910 317206 254670 0 0
t$perf-abend:sp_add_invoice_to_stock 74 74 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 4929 4929 0 0.00 0 13128 194 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 5997 126 0 0
t$perf-norm:sp_cancel_client_order 1763 1763 0 0.00 0 3095 17 0 0
t$perf-norm:sp_cancel_customer_reserve 625 625 0 0.00 0 8690 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 711 711 0 0.00 0 4179 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 609 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 469 12 0 0
t$perf-norm:sp_cancel_supplier_order 438 438 0 0.00 0 17398 1782 0 0
t$perf-norm:sp_cancel_write_off 967 967 0 0.00 0 1269 5 0 0
t$perf-norm:sp_client_order 20191 20191 0 0.00 0 11887 19 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 7771 20 0 0
t$perf-norm:sp_pay_from_customer 6930 6930 0 0.00 0 8709 14 0 0
t$perf-norm:sp_pay_to_supplier 5683 5683 0 0.00 0 7796 11 0 0
t$perf-norm:sp_reserve_write_off 9647 9647 0 0.00 0 5364 13 0 0
t$perf-norm:sp_supplier_invoice 5379 5379 0 0.00 0 7468 19 0 0
t$perf-norm:sp_supplier_order 5366 5366 0 0.00 0 4920 22 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 302 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 15 1 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 2201 29 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 7283 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 19 6 0 0
v_add_invoice_to_stock 9998 9850 148 1.48 0 5990 19 0 148
v_all_customers 36898 36898 0 0.00 0 24 1 0 0
v_all_suppliers 29890 29890 0 0.00 0 112 1 0 0
v_all_wares 40382 40382 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 2328 15 0 0
v_cancel_client_order 3526 3526 0 0.00 0 3929 7 0 0
v_cancel_customer_prepayment 1422 1422 0 0.00 0 1350 6 0 0
v_cancel_customer_reserve 1250 1250 0 0.00 0 1739 5 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 2038 6 0 0
v_cancel_supplier_invoice 756 750 6 0.79 0 2551 20 0 6
v_cancel_supplier_order 876 876 0 0.00 0 3509 8 0 0
v_cancel_write_off 1934 1934 0 0.00 0 3156 16 0 0
v_max_id_clo_ord 5366 5366 0 0.00 0 758 2 0 0
v_max_id_clo_res 934 934 0 0.00 0 4676 16 0 0
v_max_id_ord_sup 5369 5369 0 0.00 0 4592 5 0 0
v_max_non_paid_invoice 1473 1473 0 0.00 0 6435 8 0 0
v_max_non_paid_realizn 3604 3604 0 0.00 0 4891 6 0 0
v_min_id_clo_ord 5366 5366 0 0.00 0 3283 9 0 0
v_min_id_clo_res 934 934 0 0.00 0 980 5 0 0
v_min_id_ord_sup 5369 5369 0 0.00 0 7170 4 0 0
v_min_non_paid_invoice 5683 5683 0 0.00 0 14450 26 0 0
v_min_non_paid_realizn 6930 6930 0 0.00 0 7113 20 0 0
v_our_firm 10136 10136 0 0.00 0 20 1 0 0
v_reserve_write_off 19294 19294 0 0.00 0 4441 7 0 0
x_lock_depdocs_on_canc_invoice 11891 11891 0 0.00 0 14110 115 0 0
x_lock_depdocs_on_canc_sup_ord 438 438 0 0.00 0 4129 330 0 0
x_make_qstorno_1000_1200 5366 5366 0 0.00 2 58251 1251 0 0
x_make_qstorno_1200_2000 5379 5379 0 0.00 3 105096 3270 0 0
x_make_qstorno_2100_3300 460927 460927 0 0.00 0 130311 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2021 22:38:53 to 16-10-2021 22:38:57.

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
20:08:01 8 8192000000 8808183504 8822755328 12919712 0.157 4 3 4 0 13104960 78128 107120
20:09:02 18627 8192000000 9896838048 10206257152 975283520 10.638 100 88 176 0 1004354736 5577584 23493632
20:10:23 16966 8192000000 9940612992 10260652032 1018892400 11.061 100 86 172 0 1048640864 8146064 21602400
20:11:47 18228 8192000000 9965711680 10296467456 1049267520 11.354 100 87 174 0 1080929520 9874880 21787120
20:13:08 16296 8192000000 10014630016 10359349248 1104006800 11.876 100 75 150 1 1132646224 10592928 18046496
20:14:34 13560 8192000000 10061050624 10420416512 1152838464 12.336 100 75 149 1 1181403440 11499312 17065664
20:15:50 6708 8192000000 10117562768 10482360320 1193178048 12.713 100 83 165 0 1226102768 14575088 18349632
20:16:59 4157 8192000000 10145540880 10521145344 1238325632 13.131 100 76 153 0 1267283616 12137840 16820144
20:18:08 2231 8192000000 10181310384 10559463424 1263495040 13.362 100 86 171 3 1296287856 13222560 19570256
20:19:13 2754 8192000000 10199042192 10581467136 1284476448 13.554 100 94 186 3 1316990320 11617760 20896112
20:20:19 29109 8192000000 10200151984 10598432768 1297244752 13.670 100 68 133 1 1322789648 9916160 15628736
20:22:07 3921 8192000000 10209378912 10611236864 1308553472 13.773 100 54 108 0 1330771360 10574832 11643056
20:23:14 4360 8192000000 10197230608 10613829632 1312555152 13.809 100 21 42 2 1323471184 6306032 4610000
20:24:23 61507 8192000000 10211541184 10628743168 1319318496 13.871 100 24 47 0 1329975200 5538064 5118640
20:26:25 5638 8192000000 10218700080 10640158720 1328250560 13.951 100 40 80 1 1346493488 8383248 9859680
20:27:32 9632 8192000000 10215122912 10637684736 1328146688 13.950 100 24 47 2 1339733600 5137360 6449552
20:28:43 70970 8192000000 10212893296 10614849536 1255283136 13.287 95 36 71 0 1268749328 5047520 8418672
20:30:55 7150 8192000000 10156396464 10557239296 1206001616 12.832 94 33 66 1 1219819424 6748576 7069232
20:32:03 10751 8192000000 10131838608 10534211584 1218188480 12.945 97 18 36 1 1228187552 6081568 3917504
20:33:16 41189 8192000000 10153291952 10538610688 1216468160 12.929 97 45 85 1 1231109424 4350672 10290592
20:34:58 8428 8192000000 10162360864 10575237120 1268494384 13.408 99 34 68 1 1283139280 6719328 7925568
20:36:07 10830 8192000000 10180013184 10589143040 1289773344 13.602 100 34 67 0 1304052896 6242816 8036736
20:37:22 10100 8192000000 10184092112 10600779776 1298516048 13.682 100 18 36 0 1308620624 6037296 4067280
20:38:32 68418 8192000000 10227567248 10637873152 1314050256 13.823 100 54 107 0 1336593728 10183152 12360320
20:40:45 32513 8192000000 10254807296 10664312832 1341577232 14.072 100 55 108 0 1364489520 11536736 11375552
20:42:22 8058 8192000000 10269474608 10683060224 1357396000 14.214 100 61 121 0 1381880016 10845408 13638608
20:43:37 32680 8192000000 10270592640 10694557696 1369567472 14.323 100 41 82 0 1389897792 10757200 9573120
20:45:15 14314 8192000000 10284685920 10706968576 1368563056 14.314 99 41 83 1 1392763632 14879264 9321312
20:46:35 11184 8192000000 10300748560 10722070528 1387270336 14.482 100 51 100 1 1415015264 15597632 12147296
20:47:51 12963 8192000000 10311006496 10737664000 1397680992 14.574 100 50 100 3 1430373776 19868672 12824112
20:49:20 5826 8192000000 10325048944 10752643072 1409802736 14.682 100 42 84 2 1438572032 18793248 9976048
20:50:41 12792 8192000000 10340895632 10765778944 1407241888 14.659 99 51 103 1 1441122672 21390624 12490160
20:52:05 5860 8192000000 10352589888 10774654976 1419690144 14.770 100 70 137 3 1458478000 22835088 15952768
20:53:16 7245 8192000000 10355522128 10778439680 1420552208 14.778 100 70 140 1 1463148112 25232896 17363008
20:54:28 2646 8192000000 10360147968 10776391680 1415631296 14.734 100 78 154 2 1460417344 26018928 18767120
20:55:37 3915 8192000000 10371975776 10796548096 1426171712 14.827 100 68 133 3 1477419200 34263312 16984176
20:56:52 2149 8192000000 10386681040 10801098752 1425942080 14.825 100 80 160 0 1482520480 37291184 19287216
20:57:57 1432 8192000000 10383510752 10796916736 1423742432 14.806 100 89 176 2 1485668176 39600640 22325104
20:59:06 2163 8192000000 10373852464 10781671424 1408776064 14.673 100 90 181 0 1476763776 45599488 22388224
21:00:13 3844 8192000000 10364637136 10773573632 1386984048 14.479 100 85 166 3 1461923232 52088064 22851120
21:01:21 8063 8192000000 10348324176 10756694016 1370985584 14.336 99 76 150 3 1445493120 54243216 20264320
21:02:33 6675 8192000000 10329332608 10750160896 1370725872 14.334 99 48 96 2 1428831040 44628656 13476512
21:03:41 17861 8192000000 10333340528 10763321344 1388223264 14.490 100 37 74 1 1439821840 41802288 9796288
21:05:00 23906 8192000000 10326255088 10766397440 1376602528 14.386 99 21 42 0 1419781504 37354832 5824144
21:06:37 32612 8192000000 10320480848 10757722112 1372433072 14.349 99 24 47 3 1422125264 42870992 6821200
21:08:14 27773 8192000000 10300985888 10750824448 1355610464 14.198 100 19 38 0 1415370400 54086688 5673248
21:09:57 24410 8192000000 10283627856 10711257088 1343858240 14.092 98 42 82 2 1362212240 8883216 9470784
21:11:22 26944 8192000000 10274433936 10706579456 1343502544 14.089 98 20 41 0 1357966960 10404832 4059584
21:12:50 108025 8192000000 10266307808 10713731072 1285707312 13.565 95 15 31 1 1309267392 20567456 2992624
21:15:39 55428 8192000000 10277961360 10714062848 1339503104 14.053 98 24 48 2 1358971920 13817312 5651504
21:17:36 35592 8192000000 10294145264 10728173568 1364718384 14.280 100 40 80 2 1382112816 7914752 9479680
21:19:17 62095 8192000000 10285350272 10736926720 1372852848 14.353 100 14 28 0 1390238032 14475744 2909440
21:21:21 18867 8192000000 10283437136 10738819072 1357675584 14.216 99 8 15 0 1374821776 15173232 1972960
21:22:42 32975 8192000000 10290707648 10738384896 1368014320 14.309 99 19 37 0 1386617808 13706896 4896592
21:24:16 35256 8192000000 10297045456 10746191872 1380046928 14.417 99 34 68 2 1403273872 14805888 8421056
21:25:56 140130 8192000000 10303042992 10764935168 1380320608 14.419 99 18 36 0 1397544400 12953952 4269840
21:29:59 49812 8192000000 10300904272 10764849152 1394130080 14.543 100 25 50 0 1421160752 20243856 6786816
21:31:49 75671 8192000000 10297253856 10776244224 1394781376 14.549 100 7 14 0 1411452848 15129888 1541584
21:34:06 43540 8192000000 10308573216 10762764288 1400234848 14.597 100 21 41 2 1413883408 9640240 4008320
21:35:53 30395 8192000000 10319474576 10785472512 1414473952 14.724 100 22 44 0 1435482608 16187040 4821616
21:37:26 29717 8192000000 10325157856 10778701824 1413840720 14.718 100 38 76 1 1435291488 12871792 8578976
21:38:58 72130 8192000000 10326266496 10789478400 1391388144 14.518 98 23 46 1 1416721536 20191104 5142288
21:41:15 72625 8192000000 10316647072 10771636224 1370873536 14.335 97 11 21 0 1386114464 13124272 2116656
21:43:38 47767 8192000000 10317070128 10757570560 1341885808 14.074 95 34 68 1 1358621408 9153616 7581984
21:45:28 75900 8192000000 10291735216 10745470976 1314391296 13.826 94 19 38 0 1331253120 12755984 4105840
21:47:49 44388 8192000000 10295907824 10744000512 1346625904 14.117 98 15 30 1 1366598192 16531360 3440928
21:49:46 109509 8192000000 10272151456 10714984448 1312533168 13.809 95 19 38 0 1325790656 9264064 3993424
21:52:37 19229 8192000000 10282144816 10719965184 1361269968 14.249 100 36 73 1 1380433440 11076784 8086688
21:53:57 44953 8192000000 10279618592 10740428800 1370841520 14.335 99 24 49 1 1393922720 16865232 6215968
21:56:09 50370 8192000000 10255116672 10699939840 1298208464 13.679 94 12 24 0 1314306256 13565520 2532272
21:58:00 65201 8192000000 10290424832 10749452288 1374563264 14.368 100 16 32 0 1388467024 9955424 3948336
22:00:39 55417 8192000000 10264426608 10738884608 1362838896 14.263 99 13 26 0 1378694512 12819648 3035968
22:02:39 118559 8192000000 10269068688 10725457920 1316318736 13.843 96 8 16 0 1329513776 11546704 1648336
22:05:38 68982 8192000000 10280290528 10734956544 1373677264 14.360 100 20 40 3 1392224416 14054224 4492928
22:07:52 33321 8192000000 10287756320 10731188224 1355576528 14.198 99 32 64 0 1379954576 17230000 7148048
22:09:37 82656 8192000000 10279881088 10734927872 1349106944 14.139 98 18 36 1 1364056112 11263184 3685984
22:12:00 55305 8192000000 10304612416 10736697344 1341244864 14.069 97 27 54 0 1355540528 8561552 5734112
22:13:59 11274 8192000000 10302140576 10737205248 1365828000 14.290 99 30 60 0 1382192128 9978480 6385648
22:15:11 74096 8192000000 10291208960 10739871744 1355520400 14.197 98 15 28 1 1368517264 8948096 4048768
22:17:42 39365 8192000000 10293966608 10752856064 1369077088 14.319 99 21 41 1 1386740272 12676784 4986400
22:19:22 39694 8192000000 10293167952 10752946176 1390963664 14.514 100 27 52 5 1407138064 9311824 6862576
22:21:21 43428 8192000000 10291674768 10747584512 1388338848 14.491 100 13 26 0 1404470176 12789280 3342048
22:23:08 70861 8192000000 10282169520 10741116928 1353687136 14.181 97 12 24 1 1366300928 9601840 3011952
22:25:59 94366 8192000000 10288703040 10763563008 1365739872 14.289 98 9 18 0 1383394464 15673376 1981216
22:28:38 66725 8192000000 10315525712 10769240064 1398302448 14.580 100 16 32 1 1414434448 12283200 3848800
22:30:49 73394 8192000000 10295225600 10736635904 1289293936 13.598 92 11 22 0 1303867136 12018096 2555104
22:33:03 110883 8192000000 10287590896 10743365632 1337321024 14.033 97 22 44 0 1363345312 20898592 5125696
22:35:57 99707 8192000000 10288030704 10738462720 1355619120 14.198 98 20 40 0 1371374720 11717360 4038240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-10-2021 22:38:59 to 16-10-2021 22:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 74 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:01, from 16-10-2021 22:39:15 to 16-10-2021 22:39:16.

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          228490
MON$OLDEST_ACTIVE               230151
MON$OLDEST_SNAPSHOT             230151
MON$NEXT_TRANSACTION            230151
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                       2032992
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:452: 
:453: mscs-test-161.budzdorov.ru    Sat Oct 16 22:38:01 2021
:454:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:455: 
:456: 
:457: mscs-test-161.budzdorov.ru    Sat Oct 16 22:38:03 2021
:458:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:459: 
:460: 
:461: mscs-test-161.budzdorov.ru    Sat Oct 16 22:38:13 2021
:462:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:463: 
:464: 
:465: mscs-test-161.budzdorov.ru    Sat Oct 16 22:38:13 2021
:466:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:467: 
:468: 
:469: mscs-test-161.budzdorov.ru    Sat Oct 16 22:38:13 2021
:470:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:471: 
--- end of diff output ---

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

16.10.21 22:39:17. 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
2021-10-16 22:39:20 No severe PSQL-related problems occured
16.10.21 22:39:21. End of report.