Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.11.2021 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 16627296 kB
MemAvailable 31541400 kB
Buffers 0 kB
Cached 15010772 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-11-21 19:37:56
DTS_END                         2021-11-21 19:37:56.8020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57862
TRN_ID                          246063
ATT_ID                          713
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: 9493.29 23492 1139195
customer order: creation 174.33 330 20919
customer order: refuse 14.63 360 1756
order to supplier: creation 47.29 1234 5675
order to supplier: removal 3.63 88152 436
invoice (draft): creation 47.35 3392 5682
invoice (draft): removal 53.73 696 6448
invoice accept: apply 42.87 17589 5144
invoice accept: cancel 48.58 695 5830
customer reserve: creation 4010.03 173 481203
customer reserve: removal 4745.13 53 569415
realization accept: apply 84.88 199 10185
realization accept: cancel 88.40 127 10608
payment from customer: creation 61.19 132 7343
payment from customer: removal 5.56 72 667
payment to supplier: creation 49.85 167 5982
payment to supplier: removal 3.52 83 422
service: total inventory turnovers 4.97 900 597
service: total monetary turnovers 6.47 22 776
service: aggregate perf. data 0.86 18778 103
service: refresh index statistics 0.03 336679 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-11-2021 19:38:46 to 21-11-2021 19:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3807
TEST_TIME 2 3287
TEST_TIME 3 2436
TEST_TIME 4 3804
TEST_TIME 5 3930
TEST_TIME 6 3869
TEST_TIME 7 4291
TEST_TIME 8 4454
TEST_TIME 9 4638
TEST_TIME 10 5276
TEST_TIME 11 5279
TEST_TIME 12 5936
TEST_TIME 13 6626
TEST_TIME 14 6672
TEST_TIME 15 6820
TEST_TIME 16 7134
TEST_TIME 17 7676
TEST_TIME 18 7918
TEST_TIME 19 8039
TEST_TIME 20 8414
TEST_TIME 21 9017
TEST_TIME 22 9086
TEST_TIME 23 9282
TEST_TIME 24 9419
TEST_TIME 25 9305
TEST_TIME 26 9318
TEST_TIME 27 9334
TEST_TIME 28 9514
TEST_TIME 29 10548
TEST_TIME 30 10722
TEST_TIME 31 10603
TEST_TIME 32 10415
TEST_TIME 33 10311
TEST_TIME 34 10393
TEST_TIME 35 10313
TEST_TIME 36 10339
TEST_TIME 37 10253
TEST_TIME 38 10179
TEST_TIME 39 9954
TEST_TIME 40 9862
TEST_TIME 41 10093
TEST_TIME 42 10001
TEST_TIME 43 9903
TEST_TIME 44 9947
TEST_TIME 45 9827
TEST_TIME 46 9887
TEST_TIME 47 9832
TEST_TIME 48 9735
TEST_TIME 49 9746
TEST_TIME 50 9654
TEST_TIME 51 9536
TEST_TIME 52 9423
TEST_TIME 53 9584
TEST_TIME 54 9520
TEST_TIME 55 9492
TEST_TIME 56 9401
TEST_TIME 57 9291
TEST_TIME 58 9423
TEST_TIME 59 9464
TEST_TIME 60 9433
TEST_TIME 61 9497
TEST_TIME 62 9628
TEST_TIME 63 9665
TEST_TIME 64 9703
TEST_TIME 65 9664
TEST_TIME 66 9646
TEST_TIME 67 9859
TEST_TIME 68 9767
TEST_TIME 69 9823
TEST_TIME 70 9922
TEST_TIME 71 9861
TEST_TIME 72 9951
TEST_TIME 73 9964
TEST_TIME 74 9834
TEST_TIME 75 9810
TEST_TIME 76 9780
TEST_TIME 77 9725
TEST_TIME 78 9783
TEST_TIME 79 9743
TEST_TIME 80 9674
TEST_TIME 81 9703
TEST_TIME 82 9625
TEST_TIME 83 9556
TEST_TIME 84 9555
TEST_TIME 85 9503
TEST_TIME 86 9508
TEST_TIME 87 9544
TEST_TIME 88 9555
TEST_TIME 89 9553
TEST_TIME 90 9507
TEST_TIME 91 9504
TEST_TIME 92 9590
TEST_TIME 93 9588
TEST_TIME 94 9612
TEST_TIME 95 9630
TEST_TIME 96 9683
TEST_TIME 97 9653
TEST_TIME 98 9697
TEST_TIME 99 9698
TEST_TIME 100 9785
TEST_TIME 101 9759
TEST_TIME 102 9678
TEST_TIME 103 9672
TEST_TIME 104 9604
TEST_TIME 105 9581
TEST_TIME 106 9625
TEST_TIME 107 9581
TEST_TIME 108 9551
TEST_TIME 109 9539
TEST_TIME 110 9489
TEST_TIME 111 9453
TEST_TIME 112 9481
TEST_TIME 113 9474
TEST_TIME 114 9455
TEST_TIME 115 9513
TEST_TIME 116 9493
TEST_TIME 117 9506
TEST_TIME 118 9479
TEST_TIME 119 9534
TEST_TIME 120 9496

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-11-2021 19:38:50 to 21-11-2021 19:38:53.

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 1586002 1586002 0 0.00 0 25396 6 0 0
doc_list_biud 1586001 1586001 0 0.00 0 47593 24 0 0
sp_add_invoice_to_stock 5256 5144 112 2.13 29 197300 17589 0 112
sp_cancel_adding_invoice 5830 5830 0 0.00 4 55639 695 0 0
sp_cancel_client_order 1756 1756 0 0.00 5 10120 360 0 0
sp_cancel_customer_reserve 569415 569415 0 0.00 1 47594 53 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 2 3370 72 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 2 8574 84 0 0
sp_cancel_supplier_invoice 6458 6448 10 0.15 2 11463 696 0 10
sp_cancel_supplier_order 436 436 0 0.00 6 1000308 88152 0 0
sp_cancel_write_off 10608 10608 0 0.00 1 23851 128 0 0
sp_client_order 20919 20919 0 0.00 3 26532 331 0 0
sp_customer_reserve 481203 481203 0 0.00 0 86318 173 0 0
sp_fill_shopping_cart 32275 32275 0 0.00 1 14401 37 0 0
sp_fill_shopping_cart_clo_res 481196 481196 0 0.00 0 16479 12 0 0
sp_get_clo_for_invoice 5135 5135 0 0.00 0 29862 252 0 0
sp_kill_cost_storno 17527 17527 0 0.00 0 7274 54 0 0
sp_kill_qstorno_ret_qs2qd 1156322 1156322 0 0.00 0 47591 29 0 0
sp_kill_qty_storno 609812 609812 0 0.00 0 47593 63 0 0
sp_lock_selected_doc 21117 21117 0 0.00 0 7190 4 0 0
sp_make_cost_storno 28645 28645 0 0.00 0 25191 72 0 0
sp_multiply_rows_for_pdistr 28645 28645 0 0.00 0 10135 35 0 0
sp_multiply_rows_for_qdistr 20919 20919 0 0.00 0 13343 146 0 0
sp_pay_from_customer 7343 7343 0 0.00 4 14838 133 0 0
sp_pay_to_supplier 5982 5982 0 0.00 5 19721 167 0 0
sp_payment_common 13325 13325 0 0.00 1 8809 38 0 0
sp_qd_handle_on_cancel_clo 1756 1756 0 0.00 0 10113 319 0 0
sp_qd_handle_on_invoice_upd_sts 10965 10965 0 0.00 0 25268 298 0 0
sp_qd_handle_on_reserve_upd_sts 10184 10184 0 0.00 0 12303 57 0 0
sp_reserve_write_off 10185 10185 0 0.00 7 18692 200 0 0
sp_supplier_invoice 5682 5682 0 0.00 5 162552 3392 0 0
sp_supplier_order 5675 5675 0 0.00 5 54607 1234 0 0
srv_aggregate_perf_data 103 103 0 0.00 2591 256019 18778 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1658 133245 42519 0 0
srv_find_qd_qs_mism 1090702 1090702 0 0.00 0 25395 5 0 0
srv_make_invnt_saldo 597 597 0 0.00 28 24080 900 0 0
srv_make_money_saldo 776 776 0 0.00 1 686 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 267465 446933 336679 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5144 5144 0 0.00 0 25979 185 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 5620 117 0 0
t$perf-norm:sp_cancel_client_order 1756 1756 0 0.00 0 7767 21 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 2784 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 8520 30 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 3786 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 6529 28 0 0
t$perf-norm:sp_cancel_supplier_order 436 436 0 0.00 0 34777 1668 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 447 3 0 0
t$perf-norm:sp_client_order 20919 20919 0 0.00 0 23273 22 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 637 3 0 0
t$perf-norm:sp_pay_from_customer 7343 7343 0 0.00 0 22756 17 0 0
t$perf-norm:sp_pay_to_supplier 5982 5982 0 0.00 0 8792 15 0 0
t$perf-norm:sp_reserve_write_off 10185 10185 0 0.00 0 10691 19 0 0
t$perf-norm:sp_supplier_invoice 5682 5682 0 0.00 0 12070 25 0 0
t$perf-norm:sp_supplier_order 5675 5675 0 0.00 0 10568 23 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 507 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 4439 30 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 6492 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10494 10270 224 2.13 0 6242 21 0 224
v_all_customers 38856 38856 0 0.00 0 118 0 0 0
v_all_suppliers 31400 31400 0 0.00 0 17 1 0 0
v_all_wares 41838 41838 0 0.00 0 110 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 3362 11 0 0
v_cancel_client_order 3512 3512 0 0.00 0 4571 11 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 2107 7 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 2707 6 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 2500 12 0 0
v_cancel_supplier_invoice 786 766 20 2.54 0 4493 26 0 20
v_cancel_supplier_order 872 872 0 0.00 0 3378 9 0 0
v_cancel_write_off 2020 2020 0 0.00 0 2041 14 0 0
v_max_id_clo_ord 5675 5675 0 0.00 0 1658 2 0 0
v_max_id_clo_res 982 982 0 0.00 0 2704 21 0 0
v_max_id_ord_sup 5681 5681 0 0.00 0 9987 8 0 0
v_max_non_paid_invoice 1638 1638 0 0.00 0 1436 3 0 0
v_max_non_paid_realizn 3665 3665 0 0.00 0 2923 5 0 0
v_min_id_clo_ord 5675 5675 0 0.00 0 5012 7 0 0
v_min_id_clo_res 982 982 0 0.00 0 2343 7 0 0
v_min_id_ord_sup 5681 5681 0 0.00 0 5041 4 0 0
v_min_non_paid_invoice 5982 5982 0 0.00 0 8073 29 0 0
v_min_non_paid_realizn 7343 7343 0 0.00 0 8038 29 0 0
v_our_firm 10338 10338 0 0.00 0 25 1 0 0
v_reserve_write_off 20368 20368 0 0.00 0 6541 8 0 0
x_lock_depdocs_on_canc_invoice 11895 11895 0 0.00 0 22448 107 0 0
x_lock_depdocs_on_canc_sup_ord 436 436 0 0.00 0 29946 356 0 0
x_make_qstorno_1000_1200 5675 5675 0 0.00 1 54037 1153 0 0
x_make_qstorno_1200_2000 5682 5682 0 0.00 2 154344 3262 0 0
x_make_qstorno_2100_3300 481203 481203 0 0.00 0 86316 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-11-2021 19:38:54 to 21-11-2021 19:38:58.

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:00 5 8192000000 8800044784 8811548672 6309952 0.076 2 1 2 0 6409584 56848 42784
17:09:01 34168 8192000000 9925289264 10242424832 997637680 10.856 100 84 166 1 1026085296 6734384 21713232
17:10:41 21099 8192000000 9965518032 10294665216 1043118944 11.295 100 83 166 0 1073080752 8944720 21017088
17:12:08 19831 8192000000 10009116480 10348199936 1087344384 11.717 100 86 172 0 1119429824 11034032 21051408
17:13:31 16562 8192000000 10040266880 10389602304 1127050048 12.094 100 79 158 0 1157714752 12303920 18360784
17:14:50 7973 8192000000 10096340144 10457268224 1180035200 12.591 100 82 164 1 1212404352 13625920 18743232
17:16:00 5136 8192000000 10133264128 10503905280 1215811856 12.923 100 75 150 0 1245490288 13061456 16616976
17:17:07 7259 8192000000 10169358320 10545700864 1255268256 13.287 100 84 168 0 1286256016 12651200 18336560
17:18:16 1545 8192000000 10203232640 10588004352 1290346960 13.607 100 86 171 2 1319083376 9944768 18791648
17:19:18 7927 8192000000 10187798064 10603839488 1303144304 13.724 100 34 69 0 1319617376 8804112 7668960
17:20:27 10171 8192000000 10189974128 10608762880 1309223488 13.779 100 20 40 1 1319816656 6230128 4363040
17:21:37 10853 8192000000 10202088432 10620436480 1317944144 13.858 100 21 42 0 1328943728 6482016 4517568
17:22:53 11647 8192000000 10217797744 10635341824 1326169856 13.933 100 28 54 0 1338310656 5971920 6168880
17:24:28 7741 8192000000 10221515024 10644807680 1329448848 13.962 100 25 50 0 1342085360 7386304 5250208
17:25:41 11023 8192000000 10230466912 10651283456 1335743728 14.019 100 18 36 1 1345115920 5127296 4244896
17:26:53 11867 8192000000 10228056016 10646446080 1320173376 13.878 99 21 42 0 1331370464 6185968 5011120
17:28:05 65158 8192000000 10132858784 10521292800 1172645616 12.522 90 38 76 3 1188413488 6222640 9545232
17:30:12 5364 8192000000 10155601808 10557235200 1238992656 13.137 98 29 58 2 1251077760 5531280 6553824
17:31:18 8743 8192000000 10153527120 10555224064 1241741312 13.162 98 28 56 0 1254024656 5974800 6308544
17:32:29 9523 8192000000 10152891120 10560667648 1265691616 13.382 100 28 56 1 1277950224 5928736 6329872
17:33:39 48807 8192000000 10174513408 10586984448 1285551248 13.564 100 29 58 0 1298488144 6522496 6414400
17:35:29 8269 8192000000 10203205312 10610974720 1302167088 13.715 100 25 50 1 1313558784 6180784 5210912
17:36:39 9728 8192000000 10203718144 10622910464 1313717792 13.820 100 38 77 1 1333236736 10176432 9342512
17:37:50 9187 8192000000 10214159760 10629660672 1320117152 13.878 100 14 28 1 1329345664 6117568 3110944
17:39:04 9451 8192000000 10253398624 10665480192 1347695984 14.127 100 39 77 1 1366146880 9045840 9405056
17:40:18 133245 8192000000 10271633056 10698604544 1362718336 14.262 100 42 84 0 1387149824 14048800 10382688
17:43:45 15357 8192000000 10310944304 10732453888 1390869248 14.514 100 48 94 4 1420164496 17599872 11695376
17:45:15 24655 8192000000 10320808576 10750590976 1400915824 14.603 100 39 78 1 1433603920 23582720 9105376
17:46:45 4840 8192000000 10354610160 10779467776 1420382880 14.776 100 53 106 0 1463670880 30339984 12948016
17:47:57 7233 8192000000 10334450720 10759331840 1404565616 14.636 99 55 110 1 1445557024 27761888 13229520
17:49:08 9701 8192000000 10339196736 10755981312 1399116448 14.587 99 66 130 1 1448861616 34172480 15572688
17:50:25 4506 8192000000 10308602592 10723934208 1369318384 14.321 99 68 136 3 1416770624 30290208 17162032
17:51:39 2601 8192000000 10319962448 10719825920 1369226912 14.320 100 88 170 2 1419472288 29196608 21048768
17:52:48 1790 8192000000 10345760928 10755010560 1388508144 14.493 100 83 165 2 1446746400 37110480 21127776
17:53:54 3262 8192000000 10334812896 10743627776 1377320752 14.393 100 83 166 4 1441077152 43416144 20340256
17:55:05 1658 8192000000 10318451088 10726633472 1359132480 14.230 99 77 152 4 1416150960 38250816 18767664
17:56:11 9439 8192000000 10315932224 10729050112 1363363152 14.268 100 66 133 2 1417902496 37810160 16729184
17:57:27 7481 8192000000 10298268288 10733613056 1362521424 14.260 100 36 71 2 1414983936 42341456 10121056
17:58:42 23627 8192000000 10318345456 10751225856 1381420416 14.429 100 28 56 1 1424468336 35473072 7574848
18:00:13 11937 8192000000 10310633152 10742677504 1362565696 14.260 99 32 64 1 1407294384 36659408 8069280
18:01:33 18756 8192000000 10310909088 10748903424 1363784880 14.271 99 32 64 0 1415529296 43239360 8505056
18:03:04 26168 8192000000 10305077200 10738946048 1361098448 14.247 99 25 50 0 1407724768 39462848 7163472
18:04:32 16093 8192000000 10305580992 10744307712 1349519264 14.143 99 17 35 1 1398120704 43386640 5214800
18:05:59 32026 8192000000 10287184512 10745884672 1350970576 14.156 100 15 30 0 1397165408 41822064 4372768
18:07:32 62916 8192000000 10279337936 10718605312 1346638608 14.117 99 12 24 1 1362486544 12761328 3086608
18:09:38 35930 8192000000 10318181920 10740572160 1372913472 14.353 99 41 80 3 1392449488 9575792 9960224
18:11:17 60356 8192000000 10295223680 10751905792 1372559712 14.350 99 11 22 1 1393004688 18085728 2359248
18:13:18 89894 8192000000 10308165424 10750668800 1372713968 14.351 99 13 24 1 1402358528 27098352 2546208
18:15:48 88592 8192000000 10316438832 10763276288 1394131504 14.543 100 32 64 1 1413822816 12117328 7573984
18:18:24 34478 8192000000 10311883920 10758123520 1381761936 14.432 99 38 76 0 1398832672 8567776 8502960
18:20:00 29213 8192000000 10299147120 10747019264 1377944448 14.398 99 29 58 0 1392795648 8286592 6564608
18:21:30 49974 8192000000 10299560528 10756911104 1385487680 14.466 99 16 32 2 1398831808 9319056 4025072
18:23:25 45003 8192000000 10305422256 10779512832 1402169312 14.614 100 31 62 0 1422987232 13672208 7145712
18:25:20 44688 8192000000 10308669616 10775584768 1408201840 14.668 100 6 12 0 1419374672 9340640 1832192
18:27:05 23147 8192000000 10314603408 10782265344 1407600784 14.663 100 12 24 2 1425255872 14547120 3107968
18:28:34 123342 8192000000 10300016224 10771636224 1356256112 14.204 96 22 44 0 1379604016 18323360 5024544
18:31:54 2439 8192000000 10300051392 10753957888 1390440992 14.510 100 33 66 0 1407261472 9325248 7495232
18:32:56 80827 8192000000 10291460192 10758135808 1386565904 14.475 100 23 46 0 1408485728 16605200 5314624
18:35:25 66293 8192000000 10278414208 10758184960 1356978384 14.210 98 7 14 0 1379366976 20927024 1461568
18:37:32 37155 8192000000 10306782800 10760351744 1382216048 14.436 99 23 46 0 1404706768 17057888 5432832
18:39:32 55070 8192000000 10282601872 10755108864 1353869376 14.182 98 10 20 0 1376201040 19784208 2547456
18:41:28 63499 8192000000 10287424960 10744373248 1347608944 14.126 98 19 38 0 1363770704 12365952 3795808
18:43:31 53030 8192000000 10292175904 10767646720 1362877568 14.263 99 9 18 1 1383943008 19142352 1923088
18:45:40 13503 8192000000 10310096240 10738995200 1374167840 14.364 100 62 124 2 1397915648 9572720 14175088
18:46:56 83643 8192000000 10267526944 10725195776 1317978800 13.858 96 10 20 1 1335089168 14940864 2169504
18:49:25 46501 8192000000 10273792112 10739064832 1340154544 14.059 98 20 40 0 1358913344 14253168 4505632
18:51:26 76825 8192000000 10263522656 10716377088 1328162016 13.951 97 25 48 3 1343163440 8539152 6462272
18:53:47 50708 8192000000 10271430368 10718134272 1339635696 14.054 98 32 64 1 1356089984 9490912 6963376
18:55:41 54852 8192000000 10243331360 10709819392 1339408016 14.052 99 19 38 1 1352094592 8401472 4285104
18:57:38 60470 8192000000 10250246736 10707185664 1325689488 13.928 98 14 28 0 1341619408 12470576 3459344
18:59:39 46047 8192000000 10248775648 10704343040 1345150752 14.104 100 13 25 0 1359491536 11440880 2899904
19:01:25 75318 8192000000 10248398496 10707484672 1338945392 14.048 99 19 38 0 1350047840 6862816 4239632
19:03:46 93248 8192000000 10259360864 10724712448 1352218544 14.167 99 12 24 0 1365237200 10730544 2288112
19:06:20 51130 8192000000 10269140112 10722304000 1353687664 14.181 99 23 45 1 1366796960 8427056 4682240
19:08:16 52341 8192000000 10272864032 10736226304 1368445984 14.313 100 19 39 1 1390048624 17383536 4219104
19:11:24 64587 8192000000 10285523920 10741723136 1350906928 14.156 97 18 36 1 1368434272 14052096 3475248
19:13:29 37399 8192000000 10302079824 10751479808 1361815248 14.254 98 21 42 0 1373429888 7335520 4279120
19:15:14 30965 8192000000 10307584384 10755117056 1378798096 14.406 99 44 88 1 1398108816 9604864 9705856
19:17:01 80630 8192000000 10305806048 10781159424 1375859104 14.380 98 11 22 1 1399664656 21212448 2593104
19:19:22 78168 8192000000 10305715792 10764087296 1398235728 14.579 100 30 60 1 1412598192 7357552 7004912
19:21:42 23259 8192000000 10302493696 10768195584 1391431296 14.519 99 18 36 2 1406878848 11635952 3811600
19:23:05 32177 8192000000 10296496064 10776838144 1400613584 14.600 100 10 20 1 1412634080 9387712 2632784
19:24:44 47478 8192000000 10297983200 10758807552 1395740096 14.557 99 22 44 1 1410203856 9426912 5036848
19:26:32 74155 8192000000 10275967712 10741960704 1362030720 14.256 98 9 17 1 1371048240 7089072 1928448
19:29:22 80714 8192000000 10255289008 10718007296 1251961328 13.256 91 7 14 0 1261837072 8054912 1820832
19:31:42 45033 8192000000 10254800880 10711687168 1312104416 13.805 96 24 48 2 1327842048 10100240 5637392
19:33:38 17343 8192000000 10266014144 10697433088 1315841520 13.839 97 40 80 1 1333511728 8507280 9162928
19:34:56 19423 8192000000 10257851312 10695032832 1307846464 13.767 97 42 84 0 1327971456 10810560 9314432
19:36:15 51452 8192000000 10257019728 10702876672 1325221280 13.924 99 30 60 0 1353350720 21659984 6469456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-11-2021 19:39:00 to 21-11-2021 19:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-11-2021 19:39:16 to 21-11-2021 19:39:18.

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          244079
MON$OLDEST_ACTIVE               246548
MON$OLDEST_SNAPSHOT             246548
MON$NEXT_TRANSACTION            246548
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                       2046240
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 ---
:135: 
:136: mscs-test-161.budzdorov.ru    Sun Nov 21 19:37:57 2021
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:138: 
:139: 
:140: mscs-test-161.budzdorov.ru    Sun Nov 21 19:37:57 2021
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:142: 
:143: 
:144: mscs-test-161.budzdorov.ru    Sun Nov 21 19:37:58 2021
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:146: 
:147: 
:148: mscs-test-161.budzdorov.ru    Sun Nov 21 19:38:01 2021
:149:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:150: 
:151: 
:152: mscs-test-161.budzdorov.ru    Sun Nov 21 19:38:01 2021
:153:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:154: 
--- end of diff output ---

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

21.11.21 19:39:19. 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-11-21 19:39:21 No severe PSQL-related problems occured
21.11.21 19:39:22. End of report.