Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.12.2021 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16774420 kB
MemAvailable 31527316 kB
Buffers 0 kB
Cached 14927880 kB
SwapCached 0 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-12-03 19:37:59
DTS_END                         2021-12-03 19:37:59.1770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48264
TRN_ID                          232015
ATT_ID                          587
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: 9301.18 22124 1116142
customer order: creation 165.40 390 19848
customer order: refuse 14.58 364 1749
order to supplier: creation 43.36 1346 5203
order to supplier: removal 3.42 103751 410
invoice (draft): creation 44.88 3235 5386
invoice (draft): removal 54.74 767 6569
invoice accept: apply 40.27 20669 4832
invoice accept: cancel 49.98 824 5998
customer reserve: creation 3733.28 205 447993
customer reserve: removal 4868.08 58 584169
realization accept: apply 80.36 176 9643
realization accept: cancel 79.98 107 9598
payment from customer: creation 56.50 122 6780
payment from customer: removal 5.21 93 625
payment to supplier: creation 46.08 138 5529
payment to supplier: removal 3.13 94 376
service: total inventory turnovers 4.47 1318 537
service: total monetary turnovers 6.73 25 808
service: aggregate perf. data 0.71 24665 85
service: refresh index statistics 0.03 284133 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-12-2021 19:40:07 to 03-12-2021 19:40:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2867
TEST_TIME 2 3909
TEST_TIME 3 4732
TEST_TIME 4 5472
TEST_TIME 5 5748
TEST_TIME 6 5915
TEST_TIME 7 5947
TEST_TIME 8 6157
TEST_TIME 9 6349
TEST_TIME 10 6375
TEST_TIME 11 6345
TEST_TIME 12 6502
TEST_TIME 13 6379
TEST_TIME 14 6778
TEST_TIME 15 6798
TEST_TIME 16 7035
TEST_TIME 17 7082
TEST_TIME 18 7325
TEST_TIME 19 7551
TEST_TIME 20 7633
TEST_TIME 21 8041
TEST_TIME 22 8801
TEST_TIME 23 9090
TEST_TIME 24 9767
TEST_TIME 25 9794
TEST_TIME 26 9883
TEST_TIME 27 10461
TEST_TIME 28 11014
TEST_TIME 29 11075
TEST_TIME 30 11058
TEST_TIME 31 10787
TEST_TIME 32 10739
TEST_TIME 33 10503
TEST_TIME 34 10315
TEST_TIME 35 10194
TEST_TIME 36 10063
TEST_TIME 37 10093
TEST_TIME 38 10409
TEST_TIME 39 10327
TEST_TIME 40 10195
TEST_TIME 41 10191
TEST_TIME 42 10109
TEST_TIME 43 10008
TEST_TIME 44 9887
TEST_TIME 45 9915
TEST_TIME 46 9914
TEST_TIME 47 9857
TEST_TIME 48 9736
TEST_TIME 49 9738
TEST_TIME 50 9779
TEST_TIME 51 9703
TEST_TIME 52 9590
TEST_TIME 53 9598
TEST_TIME 54 9518
TEST_TIME 55 9693
TEST_TIME 56 9720
TEST_TIME 57 9746
TEST_TIME 58 9714
TEST_TIME 59 9756
TEST_TIME 60 9712
TEST_TIME 61 9698
TEST_TIME 62 9784
TEST_TIME 63 9834
TEST_TIME 64 9777
TEST_TIME 65 9908
TEST_TIME 66 9915
TEST_TIME 67 9825
TEST_TIME 68 9720
TEST_TIME 69 9675
TEST_TIME 70 9589
TEST_TIME 71 9601
TEST_TIME 72 9605
TEST_TIME 73 9564
TEST_TIME 74 9521
TEST_TIME 75 9487
TEST_TIME 76 9403
TEST_TIME 77 9426
TEST_TIME 78 9408
TEST_TIME 79 9352
TEST_TIME 80 9372
TEST_TIME 81 9377
TEST_TIME 82 9379
TEST_TIME 83 9453
TEST_TIME 84 9445
TEST_TIME 85 9447
TEST_TIME 86 9455
TEST_TIME 87 9446
TEST_TIME 88 9438
TEST_TIME 89 9547
TEST_TIME 90 9513
TEST_TIME 91 9557
TEST_TIME 92 9580
TEST_TIME 93 9490
TEST_TIME 94 9483
TEST_TIME 95 9457
TEST_TIME 96 9435
TEST_TIME 97 9396
TEST_TIME 98 9454
TEST_TIME 99 9418
TEST_TIME 100 9430
TEST_TIME 101 9405
TEST_TIME 102 9357
TEST_TIME 103 9366
TEST_TIME 104 9409
TEST_TIME 105 9381
TEST_TIME 106 9460
TEST_TIME 107 9439
TEST_TIME 108 9430
TEST_TIME 109 9500
TEST_TIME 110 9473
TEST_TIME 111 9492
TEST_TIME 112 9477
TEST_TIME 113 9539
TEST_TIME 114 9515
TEST_TIME 115 9445
TEST_TIME 116 9385
TEST_TIME 117 9393
TEST_TIME 118 9371
TEST_TIME 119 9330
TEST_TIME 120 9302

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-12-2021 19:40:10 to 03-12-2021 19:40:14.

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 1531110 1531110 0 0.00 0 25186 7 0 0
doc_list_biud 1531110 1531110 0 0.00 0 62556 28 0 0
sp_add_invoice_to_stock 4951 4832 119 2.40 39 251438 20670 0 119
sp_cancel_adding_invoice 5998 5998 0 0.00 4 72638 825 0 0
sp_cancel_client_order 1749 1749 0 0.00 4 12529 364 0 0
sp_cancel_customer_reserve 584169 584169 0 0.00 1 62560 58 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 3 4734 93 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 2 5713 94 0 0
sp_cancel_supplier_invoice 6577 6569 8 0.12 2 26390 767 0 8
sp_cancel_supplier_order 410 410 0 0.00 13 1229026 103751 0 0
sp_cancel_write_off 9598 9598 0 0.00 1 7551 107 0 0
sp_client_order 19848 19848 0 0.00 3 34887 390 0 0
sp_customer_reserve 447993 447993 0 0.00 0 46687 205 0 0
sp_fill_shopping_cart 30435 30435 0 0.00 1 16503 40 0 0
sp_fill_shopping_cart_clo_res 447988 447988 0 0.00 0 27402 14 0 0
sp_get_clo_for_invoice 4819 4819 0 0.00 0 34800 350 0 0
sp_kill_cost_storno 16597 16597 0 0.00 0 12050 57 0 0
sp_kill_qstorno_ret_qs2qd 1184915 1184915 0 0.00 0 62544 31 0 0
sp_kill_qty_storno 622952 622952 0 0.00 0 62554 68 0 0
sp_lock_selected_doc 20002 20002 0 0.00 0 8404 6 0 0
sp_make_cost_storno 26768 26768 0 0.00 0 12609 51 0 0
sp_multiply_rows_for_pdistr 26768 26768 0 0.00 0 15888 30 0 0
sp_multiply_rows_for_qdistr 19848 19848 0 0.00 0 34873 180 0 0
sp_pay_from_customer 6780 6780 0 0.00 4 17755 123 0 0
sp_pay_to_supplier 5529 5529 0 0.00 5 20308 139 0 0
sp_payment_common 12309 12309 0 0.00 2 20299 33 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 0 12504 331 0 0
sp_qd_handle_on_invoice_upd_sts 10814 10814 0 0.00 0 33661 312 0 0
sp_qd_handle_on_reserve_upd_sts 9643 9643 0 0.00 0 13089 58 0 0
sp_reserve_write_off 9643 9643 0 0.00 6 13107 176 0 0
sp_supplier_invoice 5386 5386 0 0.00 8 76516 3236 0 0
sp_supplier_order 5203 5203 0 0.00 5 61069 1346 0 0
srv_aggregate_perf_data 85 85 0 0.00 3312 602305 24666 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3471 139755 45738 0 0
srv_find_qd_qs_mism 1070379 1070379 0 0.00 0 25185 6 0 0
srv_make_invnt_saldo 537 537 0 0.00 32 71759 1319 0 0
srv_make_money_saldo 808 808 0 0.00 1 2118 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 151594 340829 284133 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4832 4832 0 0.00 0 18241 227 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 1 8182 125 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 3525 14 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 2278 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 4230 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 323 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 522 7 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 18768 1828 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 5592 13 0 0
t$perf-norm:sp_client_order 19848 19848 0 0.00 0 10071 21 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 3708 18 0 0
t$perf-norm:sp_pay_from_customer 6780 6780 0 0.00 0 6318 13 0 0
t$perf-norm:sp_pay_to_supplier 5529 5529 0 0.00 0 6282 9 0 0
t$perf-norm:sp_reserve_write_off 9643 9643 0 0.00 0 8300 11 0 0
t$perf-norm:sp_supplier_invoice 5386 5386 0 0.00 0 7704 23 0 0
t$perf-norm:sp_supplier_order 5203 5203 0 0.00 0 6297 16 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 303 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 54 2 0 0
t$perf-norm:srv_make_invnt_saldo 537 537 0 0.00 0 2229 25 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 1309 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 67 19 0 0
v_add_invoice_to_stock 9870 9632 238 2.41 0 3391 15 0 238
v_all_customers 36554 36554 0 0.00 0 17 1 0 0
v_all_suppliers 29264 29264 0 0.00 0 24 1 0 0
v_all_wares 39696 39696 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 572 6 0 0
v_cancel_client_order 3498 3498 0 0.00 0 4865 8 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 4582 9 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 240 3 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 142 2 0 0
v_cancel_supplier_invoice 778 762 16 2.05 0 3421 20 0 16
v_cancel_supplier_order 820 820 0 0.00 0 1592 6 0 0
v_cancel_write_off 1824 1824 0 0.00 0 3001 13 0 0
v_max_id_clo_ord 5203 5203 0 0.00 0 531 1 0 0
v_max_id_clo_res 960 960 0 0.00 0 2700 18 0 0
v_max_id_ord_sup 5383 5383 0 0.00 0 2605 2 0 0
v_max_non_paid_invoice 1483 1483 0 0.00 0 7311 8 0 0
v_max_non_paid_realizn 3567 3567 0 0.00 0 4626 5 0 0
v_min_id_clo_ord 5203 5203 0 0.00 0 8567 6 0 0
v_min_id_clo_res 960 960 0 0.00 0 5980 27 0 0
v_min_id_ord_sup 5383 5383 0 0.00 0 4203 6 0 0
v_min_non_paid_invoice 5529 5529 0 0.00 0 20290 23 0 0
v_min_non_paid_realizn 6780 6780 0 0.00 0 9110 25 0 0
v_our_firm 9568 9568 0 0.00 0 17 1 0 0
v_reserve_write_off 19286 19286 0 0.00 0 4446 6 0 0
x_lock_depdocs_on_canc_invoice 12186 12186 0 0.00 0 34400 117 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 7480 386 0 0
x_make_qstorno_1000_1200 5203 5203 0 0.00 1 61042 1273 0 0
x_make_qstorno_1200_2000 5386 5386 0 0.00 3 72743 3086 0 0
x_make_qstorno_2100_3300 447993 447993 0 0.00 0 46677 188 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-12-2021 19:40:15 to 03-12-2021 19:40:19.

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:03 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:03 19488 8192000000 9897299040 10204790784 979970592 10.684 100 90 180 0 1010123856 6473856 23679408
17:10:32 23965 8192000000 9939355936 10259906560 1022628192 11.097 100 90 180 0 1054533296 8779968 23125136
17:11:59 14974 8192000000 9970410064 10301341696 1054750352 11.406 100 85 170 0 1085887280 9888320 21248608
17:13:16 13923 8192000000 10018276288 10362617856 1105838192 11.893 100 84 168 0 1136803008 11139632 19825184
17:14:36 7527 8192000000 10073215856 10430750720 1159660496 12.400 100 82 162 1 1190678864 12127856 18890512
17:15:46 6322 8192000000 10102974960 10474160128 1204506416 12.818 100 76 152 1 1234714896 13027504 17180976
17:16:53 7335 8192000000 10149689360 10527817728 1242922544 13.173 100 78 156 1 1272779216 12954976 16901696
17:18:03 1735 8192000000 10198137584 10581348352 1282038224 13.532 100 91 181 2 1316140928 13820032 20282672
17:19:06 25807 8192000000 10204269376 10601013248 1300535664 13.700 100 69 138 0 1327568496 11687920 15344912
17:20:33 6116 8192000000 10204122864 10610098176 1311116976 13.796 100 34 66 3 1324988016 6348368 7522672
17:21:40 11231 8192000000 10210326992 10622832640 1319283696 13.870 100 19 38 0 1330023840 6560944 4179200
17:22:53 36385 8192000000 10203123216 10628665344 1320792848 13.884 100 19 38 0 1330990688 5645008 4552832
17:24:29 10264 8192000000 10215153168 10637119488 1326536992 13.936 100 23 45 1 1337892064 6479584 4875488
17:25:42 5922 8192000000 10224238336 10644197376 1332097536 13.986 100 23 45 1 1343414016 5456976 5859504
17:26:51 11642 8192000000 10213305968 10630459392 1304013504 13.732 98 14 28 0 1312287952 4870192 3404256
17:28:05 22751 8192000000 10200147264 10623668224 1287449008 13.581 97 18 36 1 1296363088 5030336 3883744
17:29:28 5797 8192000000 10180662112 10591371264 1268465312 13.408 98 20 38 1 1278486768 5221920 4799536
17:30:34 7571 8192000000 10167509536 10570412032 1251460528 13.252 98 26 52 0 1263174000 5153184 6560288
17:31:44 19377 8192000000 10134142352 10526187520 1145976352 12.272 91 18 35 0 1155620480 4860896 4783232
17:33:06 7117 8192000000 10130763008 10534690816 1235432800 13.104 99 41 82 0 1249957088 4587216 9937072
17:34:14 6652 8192000000 10163704176 10567614464 1270850864 13.429 100 28 56 2 1283921536 5858448 7212224
17:35:22 8721 8192000000 10181767840 10592612352 1293569200 13.637 100 17 34 1 1302499216 5260976 3669040
17:36:32 11770 8192000000 10186697056 10604036096 1300920560 13.704 100 13 26 0 1308914800 5344768 2649472
17:37:46 29963 8192000000 10216775088 10624323584 1312443984 13.808 100 48 95 0 1329357520 5596352 11317184
17:39:21 28460 8192000000 10243978096 10656391168 1334105920 14.004 100 52 104 2 1358510160 12966160 11438080
17:40:54 5488 8192000000 10259688480 10677645312 1353377744 14.178 100 49 98 2 1377378672 12009360 11991568
17:42:04 6107 8192000000 10285199632 10703532032 1369841344 14.326 100 48 96 0 1397697312 16602016 11253952
17:43:15 9672 8192000000 10308233408 10721665024 1383809504 14.451 100 66 130 0 1419651072 20669520 15172048
17:44:28 5253 8192000000 10320602272 10738720768 1394500352 14.546 100 64 126 1 1429519584 20978112 14041120
17:45:40 4132 8192000000 10333922144 10750582784 1400336320 14.598 100 70 137 1 1443123888 26676016 16111552
17:46:47 11310 8192000000 10330119488 10753028096 1402393472 14.616 100 59 117 1 1438544592 22375072 13776048
17:48:03 6896 8192000000 10345442368 10768371712 1412822032 14.709 100 60 120 0 1456861136 29480640 14558464
17:49:17 4478 8192000000 10342556896 10756730880 1390314432 14.509 99 69 137 2 1440530752 34243344 15972976
17:50:25 6953 8192000000 10347662336 10760056832 1398119728 14.578 100 84 168 2 1456169568 36819248 21230592
17:51:37 6376 8192000000 10362893536 10785177600 1411796320 14.700 100 63 123 1 1463176048 36251200 15128528
17:53:00 3466 8192000000 10359548176 10767564800 1395115328 14.551 100 80 159 4 1458676288 44026368 19534592
17:54:07 3743 8192000000 10367040240 10772103168 1400754640 14.602 100 87 173 2 1468668112 47072400 20841072
17:55:15 6568 8192000000 10383832848 10799063040 1415308608 14.731 100 75 143 0 1489124224 55618752 18196864
17:56:33 3551 8192000000 10380742656 10791141376 1403991936 14.631 100 83 166 1 1486591312 61052240 21547136
17:57:42 4256 8192000000 10388350592 10807279616 1409219968 14.677 100 64 127 0 1494498816 68114992 17163856
17:58:53 10211 8192000000 10374733312 10800549888 1389487632 14.501 99 48 96 0 1466296688 63556672 13252384
18:00:11 12127 8192000000 10335202112 10765721600 1379063248 14.408 100 41 82 0 1440096160 49114352 11918560
18:01:37 27112 8192000000 10324994672 10780319744 1381792384 14.433 100 25 50 2 1434315360 45812544 6710432
18:03:09 22599 8192000000 10314919104 10757484544 1356566640 14.207 98 23 46 1 1401529248 38117664 6844944
18:04:33 17811 8192000000 10307637008 10765254656 1368619888 14.315 100 19 38 0 1414379520 39930656 5828976
18:05:53 56678 8192000000 10277774032 10708664320 1316601312 13.846 96 24 50 1 1340243616 17795136 5847168
18:07:55 66976 8192000000 10264726448 10701111296 1306802112 13.757 96 36 70 1 1326984560 11549440 8633008
18:10:02 48017 8192000000 10260312720 10695204864 1323749536 13.911 97 51 99 2 1347101328 11771408 11580384
18:11:52 34016 8192000000 10298298080 10733912064 1363495680 14.269 99 43 86 0 1393619344 19810640 10313024
18:13:33 47190 8192000000 10294332208 10751606784 1369367872 14.321 99 22 43 0 1403102800 28632176 5102752
18:16:06 69207 8192000000 10280965328 10727428096 1353617040 14.180 98 14 28 1 1365481376 8835248 3029088
18:18:17 27159 8192000000 10280840256 10734505984 1380469584 14.421 100 13 26 0 1399709264 16403344 2836336
18:20:07 49137 8192000000 10280114224 10739625984 1377399488 14.393 100 16 34 0 1393195264 12054144 3741632
18:22:16 107081 8192000000 10284006080 10738196480 1369709056 14.324 99 18 36 0 1385633280 12319920 3604304
18:25:14 66147 8192000000 10283553072 10746699776 1385069184 14.462 100 20 40 1 1403778560 13557440 5151936
18:27:21 50426 8192000000 10279608288 10753572864 1383263136 14.446 100 6 12 0 1398972608 14305872 1403600
18:29:18 70024 8192000000 10278647216 10750943232 1384255408 14.455 100 11 20 1 1400064864 13228624 2580832
18:31:44 112292 8192000000 10296447344 10763988992 1381835840 14.433 99 10 20 1 1400591680 16642432 2113408
18:34:51 75784 8192000000 10300724416 10765516800 1390586688 14.511 99 12 24 1 1402753136 9370272 2796176
18:37:08 15905 8192000000 10313686400 10755026944 1382604416 14.440 99 34 67 0 1401543440 9564688 9374336
18:38:24 108511 8192000000 10297072096 10756435968 1332926320 13.994 95 26 52 1 1353891856 15465136 5500400
18:41:14 115095 8192000000 10286871120 10736881664 1329504320 13.963 96 10 20 0 1346711376 15118800 2088256
18:44:10 12697 8192000000 10307180800 10749448192 1387733264 14.486 100 53 103 5 1407558016 8067696 11757056
18:45:43 58048 8192000000 10303910080 10759540736 1385965872 14.470 99 33 66 0 1404208672 11012944 7229856
18:48:23 92013 8192000000 10275899072 10749059072 1351820896 14.164 97 15 30 0 1370841760 15085504 3935360
18:50:55 44017 8192000000 10256228400 10710142976 1349314432 14.141 98 17 34 1 1362874400 9473904 4086064
18:52:45 57538 8192000000 10252654496 10706542592 1333377328 13.998 97 20 40 0 1354537504 16984672 4175504
18:55:33 69661 8192000000 10242684976 10708377600 1307169504 13.760 96 7 14 0 1319128496 10396480 1562512
18:57:42 43372 8192000000 10265715632 10709082112 1331658144 13.982 98 35 70 0 1348882336 9222272 8001920
18:59:38 39240 8192000000 10279684672 10724167680 1340466384 14.062 98 29 58 0 1361894880 14905808 6522688
19:01:26 37767 8192000000 10271663472 10713182208 1331679680 13.982 98 33 66 0 1356036912 16997056 7360176
19:03:26 33161 8192000000 10263024432 10699485184 1341786496 14.074 99 36 72 0 1360629808 10594288 8249024
19:05:02 56524 8192000000 10245876224 10681307136 1286193936 13.570 96 46 92 0 1311828336 15945376 9689024
19:07:00 99455 8192000000 10243939520 10689921024 1279808928 13.511 95 12 24 0 1297634528 15146064 2679536
19:09:44 139754 8192000000 10241762656 10696679424 1320430592 13.881 97 28 54 1 1341584288 14481152 6672544
19:13:14 72347 8192000000 10263740080 10719649792 1362750880 14.262 100 10 20 2 1380405568 15285440 2369248
19:15:27 56282 8192000000 10280584928 10728828928 1347882544 14.128 98 15 31 0 1365332240 13862032 3587664
19:17:27 50257 8192000000 10270221984 10737930240 1362803536 14.263 99 25 50 1 1381075744 11865520 6406688
19:19:22 60758 8192000000 10277398368 10735476736 1377242832 14.392 99 22 44 0 1393449136 10441792 5764512
19:21:23 61426 8192000000 10294986048 10758262784 1379349040 14.411 99 21 43 0 1395772528 11073120 5350368
19:23:24 71902 8192000000 10286236176 10742923264 1322269792 13.897 95 13 26 0 1341152480 15533840 3348848
19:25:45 98389 8192000000 10289356032 10753085440 1358324592 14.222 98 20 39 0 1378804016 16560576 3918848
19:28:24 26147 8192000000 10294095856 10746277888 1383588352 14.449 100 27 54 0 1400419824 10962480 5868992
19:29:52 98815 8192000000 10279302176 10737811456 1348088896 14.130 98 17 34 0 1364144656 12415696 3640064
19:32:31 82301 8192000000 10286391520 10729758720 1349712560 14.145 98 24 48 0 1362539568 7528736 5298272
19:34:54 54386 8192000000 10266986496 10732900352 1377858256 14.397 100 16 32 1 1389084768 7545776 3680736
19:37:03 54630 8192000000 10265308544 10716971008 1357047600 14.211 98 19 38 1 1374001792 12544032 4410160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-12-2021 19:40:21 to 03-12-2021 19:40:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-12-2021 19:40:36 to 03-12-2021 19:40:38.

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          230214
MON$OLDEST_ACTIVE               232194
MON$OLDEST_SNAPSHOT             232194
MON$NEXT_TRANSACTION            232194
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                       2024800
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:771: 
:772: mscs-test-161.budzdorov.ru    Fri Dec  3 19:38:12 2021
:773:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:774: 
:775: 
:776: mscs-test-161.budzdorov.ru    Fri Dec  3 19:38:13 2021
:777:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:778: 
--- end of diff output ---

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

03.12.21 19:40:39. 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-12-03 19:40:42 No severe PSQL-related problems occured
03.12.21 19:40:43. End of report.