Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.11.2021 01: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 16609528 kB
MemAvailable 31554876 kB
Buffers 0 kB
Cached 15057376 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-24 01:37:54
DTS_END                         2021-11-24 01:37:54.0140
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/36616
TRN_ID                          244620
ATT_ID                          702
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: 9228.77 16911 1107452
customer order: creation 180.29 341 21635
customer order: refuse 15.29 333 1835
order to supplier: creation 48.50 1351 5820
order to supplier: removal 3.44 94901 413
invoice (draft): creation 47.81 3588 5737
invoice (draft): removal 52.93 788 6351
invoice accept: apply 42.02 17874 5042
invoice accept: cancel 47.34 869 5681
customer reserve: creation 3840.21 177 460825
customer reserve: removal 4643.79 54 557255
realization accept: apply 87.26 197 10471
realization accept: cancel 85.44 108 10253
payment from customer: creation 61.63 135 7395
payment from customer: removal 6.33 94 759
payment to supplier: creation 49.59 155 5951
payment to supplier: removal 3.64 127 437
service: total inventory turnovers 5.03 1002 604
service: total monetary turnovers 7.34 26 881
service: aggregate perf. data 0.86 16595 103
service: refresh index statistics 0.03 199505 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-11-2021 01:39:14 to 24-11-2021 01:39:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3381
TEST_TIME 2 2901
TEST_TIME 3 3212
TEST_TIME 4 3199
TEST_TIME 5 4514
TEST_TIME 6 4932
TEST_TIME 7 5169
TEST_TIME 8 5247
TEST_TIME 9 5218
TEST_TIME 10 5371
TEST_TIME 11 5461
TEST_TIME 12 5775
TEST_TIME 13 5778
TEST_TIME 14 5870
TEST_TIME 15 5981
TEST_TIME 16 6089
TEST_TIME 17 6144
TEST_TIME 18 6306
TEST_TIME 19 6314
TEST_TIME 20 6537
TEST_TIME 21 6802
TEST_TIME 22 7066
TEST_TIME 23 7636
TEST_TIME 24 7864
TEST_TIME 25 8353
TEST_TIME 26 8819
TEST_TIME 27 9436
TEST_TIME 28 9691
TEST_TIME 29 10282
TEST_TIME 30 10110
TEST_TIME 31 10293
TEST_TIME 32 10212
TEST_TIME 33 10201
TEST_TIME 34 10160
TEST_TIME 35 9931
TEST_TIME 36 9803
TEST_TIME 37 9860
TEST_TIME 38 9842
TEST_TIME 39 9923
TEST_TIME 40 9746
TEST_TIME 41 9774
TEST_TIME 42 9687
TEST_TIME 43 9506
TEST_TIME 44 9771
TEST_TIME 45 9767
TEST_TIME 46 9670
TEST_TIME 47 9578
TEST_TIME 48 9633
TEST_TIME 49 9466
TEST_TIME 50 9441
TEST_TIME 51 9374
TEST_TIME 52 9319
TEST_TIME 53 9332
TEST_TIME 54 9409
TEST_TIME 55 9484
TEST_TIME 56 9409
TEST_TIME 57 9403
TEST_TIME 58 9403
TEST_TIME 59 9535
TEST_TIME 60 9489
TEST_TIME 61 9560
TEST_TIME 62 9617
TEST_TIME 63 9602
TEST_TIME 64 9602
TEST_TIME 65 9512
TEST_TIME 66 9485
TEST_TIME 67 9467
TEST_TIME 68 9400
TEST_TIME 69 9348
TEST_TIME 70 9270
TEST_TIME 71 9258
TEST_TIME 72 9240
TEST_TIME 73 9231
TEST_TIME 74 9242
TEST_TIME 75 9254
TEST_TIME 76 9273
TEST_TIME 77 9270
TEST_TIME 78 9288
TEST_TIME 79 9338
TEST_TIME 80 9288
TEST_TIME 81 9339
TEST_TIME 82 9407
TEST_TIME 83 9485
TEST_TIME 84 9522
TEST_TIME 85 9470
TEST_TIME 86 9533
TEST_TIME 87 9451
TEST_TIME 88 9388
TEST_TIME 89 9357
TEST_TIME 90 9311
TEST_TIME 91 9301
TEST_TIME 92 9261
TEST_TIME 93 9262
TEST_TIME 94 9233
TEST_TIME 95 9280
TEST_TIME 96 9289
TEST_TIME 97 9292
TEST_TIME 98 9279
TEST_TIME 99 9292
TEST_TIME 100 9363
TEST_TIME 101 9431
TEST_TIME 102 9423
TEST_TIME 103 9424
TEST_TIME 104 9504
TEST_TIME 105 9474
TEST_TIME 106 9466
TEST_TIME 107 9515
TEST_TIME 108 9462
TEST_TIME 109 9425
TEST_TIME 110 9368
TEST_TIME 111 9342
TEST_TIME 112 9326
TEST_TIME 113 9311
TEST_TIME 114 9323
TEST_TIME 115 9276
TEST_TIME 116 9277
TEST_TIME 117 9272
TEST_TIME 118 9241
TEST_TIME 119 9233
TEST_TIME 120 9230

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-11-2021 01:39:17 to 24-11-2021 01:39:21.

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 1535913 1535913 0 0.00 0 23862 7 0 0
doc_list_biud 1535913 1535913 0 0.00 0 19122 26 0 0
sp_add_invoice_to_stock 5176 5042 134 2.58 18 175544 17874 0 134
sp_cancel_adding_invoice 5681 5681 0 0.00 4 65915 869 0 0
sp_cancel_client_order 1835 1835 0 0.00 4 13059 333 0 0
sp_cancel_customer_reserve 557255 557255 0 0.00 1 18321 55 0 0
sp_cancel_pay_from_customer 759 759 0 0.00 3 7331 94 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 13726 128 0 0
sp_cancel_supplier_invoice 6356 6351 5 0.07 2 19131 789 0 5
sp_cancel_supplier_order 413 413 0 0.00 8 1272949 94901 0 0
sp_cancel_write_off 10253 10253 0 0.00 1 7754 108 0 0
sp_client_order 21635 21635 0 0.00 3 20848 341 0 0
sp_customer_reserve 460825 460825 0 0.00 0 56720 178 0 0
sp_fill_shopping_cart 33184 33184 0 0.00 1 14639 42 0 0
sp_fill_shopping_cart_clo_res 460820 460820 0 0.00 0 18817 12 0 0
sp_get_clo_for_invoice 5037 5037 0 0.00 0 18512 278 0 0
sp_kill_cost_storno 17130 17130 0 0.00 0 11562 56 0 0
sp_kill_qstorno_ret_qs2qd 1131527 1131527 0 0.00 0 19117 30 0 0
sp_kill_qty_storno 597296 597296 0 0.00 0 19120 66 0 0
sp_lock_selected_doc 21532 21532 0 0.00 0 9457 7 0 0
sp_make_cost_storno 28854 28854 0 0.00 0 23856 64 0 0
sp_multiply_rows_for_pdistr 28854 28854 0 0.00 0 12566 33 0 0
sp_multiply_rows_for_qdistr 21635 21635 0 0.00 0 10754 150 0 0
sp_pay_from_customer 7395 7395 0 0.00 4 15930 136 0 0
sp_pay_to_supplier 5951 5951 0 0.00 4 15836 156 0 0
sp_payment_common 13346 13346 0 0.00 2 15829 40 0 0
sp_qd_handle_on_cancel_clo 1835 1835 0 0.00 0 13049 298 0 0
sp_qd_handle_on_invoice_upd_sts 10718 10718 0 0.00 0 17369 344 0 0
sp_qd_handle_on_reserve_upd_sts 10471 10471 0 0.00 0 11424 54 0 0
sp_reserve_write_off 10471 10471 0 0.00 7 15816 197 0 0
sp_supplier_invoice 5737 5737 0 0.00 5 71477 3589 0 0
sp_supplier_order 5820 5820 0 0.00 5 54680 1352 0 0
srv_aggregate_perf_data 103 103 0 0.00 2598 150765 16596 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1704 128503 45730 0 0
srv_find_qd_qs_mism 1059338 1059338 0 0.00 0 17565 5 0 0
srv_make_invnt_saldo 604 604 0 0.00 29 25463 1002 0 0
srv_make_money_saldo 881 881 0 0.00 1 1347 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 79096 309069 199506 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5042 5042 0 0.00 0 13281 165 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 4628 91 0 0
t$perf-norm:sp_cancel_client_order 1835 1835 0 0.00 0 3864 15 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 4851 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 759 759 0 0.00 0 672 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 943 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 2798 31 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 17549 1517 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 5296 15 0 0
t$perf-norm:sp_client_order 21635 21635 0 0.00 0 12226 22 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 4331 10 0 0
t$perf-norm:sp_pay_from_customer 7395 7395 0 0.00 0 7163 13 0 0
t$perf-norm:sp_pay_to_supplier 5951 5951 0 0.00 0 7022 11 0 0
t$perf-norm:sp_reserve_write_off 10471 10471 0 0.00 0 7800 11 0 0
t$perf-norm:sp_supplier_invoice 5737 5737 0 0.00 0 16257 28 0 0
t$perf-norm:sp_supplier_order 5820 5820 0 0.00 0 5731 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 4199 92 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 604 604 0 0.00 0 2730 27 0 0
t$perf-norm:srv_make_money_saldo 881 881 0 0.00 0 5083 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 10342 10074 268 2.59 0 16938 22 0 268
v_all_customers 39082 39082 0 0.00 0 23 1 0 0
v_all_suppliers 31364 31364 0 0.00 0 24 1 0 0
v_all_wares 43270 43270 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 15400 23 0 0
v_cancel_client_order 3670 3670 0 0.00 0 2977 7 0 0
v_cancel_customer_prepayment 1518 1518 0 0.00 0 884 4 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 2654 7 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 13720 19 0 0
v_cancel_supplier_invoice 882 872 10 1.13 0 6926 24 0 10
v_cancel_supplier_order 826 826 0 0.00 0 421 4 0 0
v_cancel_write_off 1990 1990 0 0.00 0 2324 13 0 0
v_max_id_clo_ord 5819 5819 0 0.00 0 3299 3 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 1609 11 0 0
v_max_id_ord_sup 5730 5730 0 0.00 0 4361 6 0 0
v_max_non_paid_invoice 1818 1818 0 0.00 0 1078 3 0 0
v_max_non_paid_realizn 4056 4056 0 0.00 0 6640 7 0 0
v_min_id_clo_ord 5819 5819 0 0.00 0 3350 9 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 1075 4 0 0
v_min_id_ord_sup 5730 5730 0 0.00 0 6579 10 0 0
v_min_non_paid_invoice 5951 5951 0 0.00 0 10004 35 0 0
v_min_non_paid_realizn 7395 7395 0 0.00 0 9452 28 0 0
v_our_firm 10866 10866 0 0.00 0 14 1 0 0
v_reserve_write_off 20942 20942 0 0.00 0 15437 8 0 0
x_lock_depdocs_on_canc_invoice 11596 11596 0 0.00 0 15830 113 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 3497 313 0 0
x_make_qstorno_1000_1200 5820 5820 0 0.00 1 54634 1262 0 0
x_make_qstorno_1200_2000 5737 5737 0 0.00 2 63498 3435 0 0
x_make_qstorno_2100_3300 460825 460825 0 0.00 0 56717 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-11-2021 01:39:22 to 24-11-2021 01:39:26.

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
23:07:57 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:08:58 35923 8192000000 9914460528 10230288384 989561744 10.777 100 86 172 0 1017901872 6238336 22101792
23:10:40 10516 8192000000 9959866304 10285133824 1044662480 11.309 100 89 178 0 1075612800 8264144 22686176
23:11:58 18300 8192000000 9993383824 10330841088 1068711712 11.540 100 85 169 0 1100074976 9802912 21560352
23:13:30 16374 8192000000 10056426768 10407583744 1139481136 12.211 100 85 169 1 1170366528 11119392 19766000
23:14:47 6926 8192000000 10099807488 10463383552 1186998176 12.655 100 82 163 0 1218386432 12391568 18996688
23:15:58 4821 8192000000 10146068592 10522963968 1236498816 13.114 100 76 152 0 1266265312 13067248 16699248
23:17:04 5055 8192000000 10170096176 10548899840 1255714256 13.291 100 79 158 2 1288072416 14451376 17906784
23:18:12 1545 8192000000 10207517312 10590670848 1288650640 13.592 100 93 187 1 1323219952 13688128 20881184
23:19:15 6837 8192000000 10206631520 10598539264 1299764784 13.693 100 62 122 1 1322423536 9108080 13550672
23:20:22 2926 8192000000 10196846112 10607976448 1308981536 13.777 100 39 76 0 1324760528 6640896 9138096
23:21:26 6368 8192000000 10202699328 10620076032 1321714816 13.892 100 31 62 1 1335667456 6941472 7011168
23:22:32 6737 8192000000 10206438752 10628100096 1326998912 13.940 100 18 36 2 1336855984 5706432 4150640
23:23:42 19704 8192000000 10221600640 10648227840 1334734880 14.010 100 26 52 1 1346485792 5999440 5751472
23:25:02 2892 8192000000 10232159440 10653372416 1335668208 14.018 100 43 86 1 1354422608 9223488 9530912
23:26:11 8018 8192000000 10233973856 10653691904 1338544192 14.044 100 29 58 1 1351664272 6153056 6967024
23:27:20 9504 8192000000 10205723728 10625290240 1300881296 13.703 98 14 28 0 1309399312 5371152 3146864
23:28:32 59488 8192000000 10201338352 10618789888 1294546944 13.646 98 30 60 0 1307297728 5437248 7313536
23:30:35 6101 8192000000 10151967808 10537795584 1206897376 12.840 96 31 62 2 1218618240 5090560 6630304
23:31:42 9175 8192000000 10155700784 10551136256 1235416688 13.104 98 21 42 1 1245034656 4739024 4878944
23:32:53 11583 8192000000 10158653328 10568372224 1260270016 13.332 99 15 31 0 1269272768 5157568 3845184
23:34:06 19159 8192000000 10207916864 10593083392 1274292368 13.461 99 73 146 0 1299641280 6529872 18819040
23:35:26 6850 8192000000 10174298736 10589655040 1288194880 13.588 100 27 54 0 1300512960 5772160 6545920
23:36:35 6540 8192000000 10196893440 10611380224 1304041536 13.732 100 16 32 1 1312933840 5589664 3302640
23:37:42 19264 8192000000 10222694736 10634203136 1325588432 13.927 100 16 32 0 1334234336 5317872 3328032
23:39:02 37518 8192000000 10244723120 10656915456 1339256496 14.051 100 54 108 1 1360412064 8801536 12354032
23:40:54 34157 8192000000 10267620912 10685145088 1359930768 14.237 100 55 110 2 1385006624 13173120 11902736
23:42:36 11739 8192000000 10282549344 10708406272 1373482672 14.358 100 49 98 0 1397495968 12199760 11813536
23:43:52 14300 8192000000 10300905648 10721128448 1385376352 14.465 100 65 129 0 1415016896 14939440 14701104
23:45:14 22803 8192000000 10305174656 10733506560 1396603360 14.565 100 37 74 0 1417729008 12308784 8816864
23:46:44 7183 8192000000 10299931840 10723590144 1371699360 14.342 98 41 81 3 1397372640 15536992 10136288
23:47:57 1940 8192000000 10334774384 10748731392 1401185680 14.606 100 78 156 0 1438925968 18194928 19545360
23:49:04 7485 8192000000 10322532432 10739240960 1379603568 14.413 99 60 120 1 1415127728 21146176 14377984
23:50:20 4510 8192000000 10334738448 10752307200 1403358336 14.625 100 77 154 1 1443052128 20028912 19664880
23:51:30 10753 8192000000 10349433296 10773307392 1412002016 14.702 100 59 118 3 1456961456 29162704 15796736
23:52:44 5895 8192000000 10353269792 10774904832 1408400336 14.670 100 62 124 0 1460855296 36854448 15600512
23:54:02 2367 8192000000 10340583248 10749779968 1390681824 14.512 100 87 173 1 1447368224 36310320 20376080
23:55:09 1704 8192000000 10339927552 10742042624 1383255632 14.446 100 92 182 2 1441450352 34784320 23410400
23:56:16 3866 8192000000 10330166192 10730704896 1363088848 14.265 99 83 167 3 1423006128 38722752 21194528
23:57:29 2395 8192000000 10332994368 10730426368 1362295856 14.258 99 89 174 0 1433138944 47900352 22942736
23:58:35 3622 8192000000 10345212144 10749734912 1374788704 14.370 100 69 137 1 1445273040 52633872 17850464
23:59:45 6537 8192000000 10333756464 10745630720 1364457056 14.277 100 54 107 1 1436205680 56927984 14820640
00:00:56 3992 8192000000 10328347408 10740834304 1362513872 14.260 100 67 134 1 1432283120 52635808 17133440
00:02:03 25168 8192000000 10309654272 10742358016 1353431152 14.178 99 35 70 2 1417637840 54142016 10064672
00:03:43 12289 8192000000 10319081504 10756239360 1371325888 14.339 100 38 76 0 1430488832 49216512 9946432
00:04:58 29510 8192000000 10284895776 10735607808 1358661520 14.225 100 19 37 0 1398565792 34878272 5026000
00:06:30 38283 8192000000 10280909232 10733273088 1336277168 14.024 98 13 26 0 1357302960 18231920 2793872
00:08:09 42797 8192000000 10273392896 10723004416 1352897216 14.174 99 8 16 0 1372446592 17590480 1958896
00:09:52 108267 8192000000 10296954464 10747928576 1385786848 14.468 100 19 37 3 1402162064 12011776 4363440
00:12:41 49485 8192000000 10302494672 10746183680 1365561008 14.287 98 42 82 0 1381601072 6465088 9574976
00:14:41 91033 8192000000 10288384384 10744119296 1335220240 14.014 96 13 26 1 1354940848 16693872 3026736
00:17:14 38344 8192000000 10291443760 10752937984 1369928240 14.326 99 11 22 2 1390637840 18377952 2331648
00:19:41 31828 8192000000 10319771328 10758713344 1399973952 14.595 100 26 52 0 1417298736 10613632 6711152
00:21:18 39458 8192000000 10297257968 10770604032 1383014784 14.443 99 9 18 0 1403165152 17330352 2820016
00:23:10 55049 8192000000 10304840912 10760011776 1388921440 14.496 99 5 10 0 1401453392 11563440 968512
00:25:11 31233 8192000000 10303200960 10778095616 1408742784 14.673 100 9 18 0 1422789520 11808976 2237760
00:26:47 62026 8192000000 10306276720 10770010112 1404297968 14.633 100 11 22 0 1414807616 8323904 2185744
00:28:50 56471 8192000000 10306465696 10771025920 1383877696 14.451 98 6 12 0 1392873776 7691120 1304960
00:30:50 72928 8192000000 10304438096 10778324992 1381086192 14.426 98 16 32 2 1402169600 16764784 4318624
00:33:06 35244 8192000000 10305559920 10745409536 1347605520 14.126 97 19 39 1 1358892288 7107024 4179744
00:34:44 60745 8192000000 10280993616 10733604864 1306091424 13.751 94 27 54 1 1327918736 15680112 6147200
00:36:53 47299 8192000000 10279136864 10719244288 1323968544 13.913 96 29 58 0 1339524032 9062240 6493248
00:38:42 122783 8192000000 10265987568 10708402176 1258252512 13.314 91 20 38 2 1276366000 14145648 3967840
00:41:53 104830 8192000000 10269311632 10723573760 1369229088 14.320 100 23 46 1 1383924400 9347584 5347728
00:44:41 55155 8192000000 10265436928 10717605888 1343084624 14.085 98 15 30 1 1354741456 8138576 3518256
00:46:36 39562 8192000000 10262933232 10721619968 1368070752 14.310 100 11 22 0 1380003072 9293776 2638544
00:48:21 66192 8192000000 10252657840 10716078080 1339637664 14.054 98 16 32 2 1353324912 9830688 3856560
00:50:27 64334 8192000000 10265188720 10734112768 1365448496 14.286 100 9 18 1 1376003056 8707984 1846576
00:52:54 58745 8192000000 10272690128 10722971648 1335508160 14.017 97 17 34 1 1350808576 11577488 3722928
00:54:55 43664 8192000000 10306731632 10751373312 1380185168 14.418 100 28 57 1 1401609232 14915840 6508224
00:56:43 71185 8192000000 10294345488 10738810880 1354631696 14.189 98 30 60 0 1371661040 10483136 6546208
00:58:55 51962 8192000000 10287374048 10734051328 1361980576 14.255 99 18 36 0 1379363888 13128512 4254800
01:00:50 83337 8192000000 10293710128 10747162624 1370583008 14.332 99 15 28 0 1385880160 12208672 3088480
01:03:17 12450 8192000000 10323759312 10759098368 1388467152 14.492 100 52 103 1 1417044128 17598992 10977984
01:04:36 89134 8192000000 10283129232 10744008704 1380376048 14.420 99 31 62 1 1396124608 8795840 6952720
01:07:16 79148 8192000000 10286013104 10751721472 1366830368 14.299 98 9 18 0 1381204576 12483008 1891200
01:09:36 71910 8192000000 10295533072 10760839168 1369485584 14.322 98 12 24 0 1383069168 11209296 2374288
01:11:53 52279 8192000000 10305994400 10761920512 1362028960 14.256 97 15 29 1 1380376304 15017936 3329408
01:13:57 46096 8192000000 10303012576 10755203072 1368089776 14.310 98 21 42 1 1387277024 14040432 5146816
01:15:47 38358 8192000000 10328611584 10780332032 1404335808 14.634 100 21 42 1 1423863888 14638688 4889392
01:17:25 128503 8192000000 10303720224 10759938048 1342686464 14.082 96 10 19 0 1365101744 20235344 2179936
01:20:34 29322 8192000000 10309282496 10756726784 1362520768 14.260 98 30 60 1 1384774864 15186240 7067856
01:22:11 61725 8192000000 10307056480 10755633152 1367211008 14.302 98 24 48 1 1386997264 14807488 4978768
01:24:13 76161 8192000000 10270520480 10720022528 1339673232 14.054 98 7 14 0 1356587440 15277648 1636560
01:26:32 99608 8192000000 10261171360 10708189184 1287060048 13.577 94 22 44 0 1302431792 10186208 5185536
01:29:12 68244 8192000000 10244326192 10700681216 1306363200 13.753 96 8 16 0 1317621456 9375968 1882288
01:31:20 102682 8192000000 10259691600 10712576000 1332742576 13.992 98 7 14 0 1343126992 8897040 1487376
01:34:35 97893 8192000000 10238202464 10692210688 1286486032 13.572 95 16 32 1 1302315824 12302432 3527360
01:37:24 27589 8192000000 10260452672 10707898368 1330461120 13.971 97 28 55 3 1343863088 7124560 6277408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-11-2021 01:39:28 to 24-11-2021 01:39:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-11-2021 01:39:43 to 24-11-2021 01:39:45.

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          243681
MON$OLDEST_ACTIVE               245598
MON$OLDEST_SNAPSHOT             245598
MON$NEXT_TRANSACTION            245598
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                       2049568
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 ---
:265: 
:266: mscs-test-161.budzdorov.ru    Wed Nov 24 01:37:55 2021
:267:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:268: 
:269: 
:270: mscs-test-161.budzdorov.ru    Wed Nov 24 01:37:58 2021
:271:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:272: 
:273: 
:274: mscs-test-161.budzdorov.ru    Wed Nov 24 01:37:58 2021
:275:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:276: 
--- end of diff output ---

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

24.11.21 01:39:46. 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-24 01:39:49 No severe PSQL-related problems occured
24.11.21 01:39:50. End of report.