Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.11.2021 22:40
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 16790432 kB
MemAvailable 31582236 kB
Buffers 0 kB
Cached 14916736 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-25 22:38:00
DTS_END                         2021-11-25 22:38:00.9140
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/37620
TRN_ID                          236589
ATT_ID                          709
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: 9213.03 26553 1105564
customer order: creation 165.52 355 19862
customer order: refuse 14.30 389 1716
order to supplier: creation 44.45 1219 5334
order to supplier: removal 3.33 110736 399
invoice (draft): creation 44.65 3748 5358
invoice (draft): removal 53.25 864 6390
invoice accept: apply 40.43 20145 4852
invoice accept: cancel 48.18 777 5781
customer reserve: creation 3779.26 196 453511
customer reserve: removal 4727.20 60 567264
realization accept: apply 81.78 191 9813
realization accept: cancel 86.58 116 10389
payment from customer: creation 56.93 125 6832
payment from customer: removal 5.38 91 645
payment to supplier: creation 46.98 132 5638
payment to supplier: removal 3.26 61 391
service: total inventory turnovers 4.46 1243 535
service: total monetary turnovers 6.28 29 754
service: aggregate perf. data 0.80 21005 96
service: refresh index statistics 0.03 369571 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-11-2021 22:40:23 to 25-11-2021 22:40:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4497
TEST_TIME 2 4397
TEST_TIME 3 5084
TEST_TIME 4 4856
TEST_TIME 5 5017
TEST_TIME 6 4881
TEST_TIME 7 5341
TEST_TIME 8 5428
TEST_TIME 9 5760
TEST_TIME 10 5625
TEST_TIME 11 5950
TEST_TIME 12 6040
TEST_TIME 13 6323
TEST_TIME 14 6327
TEST_TIME 15 6387
TEST_TIME 16 6432
TEST_TIME 17 6919
TEST_TIME 18 7253
TEST_TIME 19 7730
TEST_TIME 20 7750
TEST_TIME 21 8160
TEST_TIME 22 8805
TEST_TIME 23 9029
TEST_TIME 24 9380
TEST_TIME 25 9716
TEST_TIME 26 9998
TEST_TIME 27 10296
TEST_TIME 28 10563
TEST_TIME 29 10854
TEST_TIME 30 10547
TEST_TIME 31 10533
TEST_TIME 32 10477
TEST_TIME 33 10313
TEST_TIME 34 10482
TEST_TIME 35 10291
TEST_TIME 36 10148
TEST_TIME 37 10150
TEST_TIME 38 10014
TEST_TIME 39 9887
TEST_TIME 40 9859
TEST_TIME 41 9698
TEST_TIME 42 9654
TEST_TIME 43 9556
TEST_TIME 44 9546
TEST_TIME 45 9615
TEST_TIME 46 9522
TEST_TIME 47 9514
TEST_TIME 48 9500
TEST_TIME 49 9419
TEST_TIME 50 9370
TEST_TIME 51 9450
TEST_TIME 52 9447
TEST_TIME 53 9317
TEST_TIME 54 9351
TEST_TIME 55 9432
TEST_TIME 56 9417
TEST_TIME 57 9395
TEST_TIME 58 9481
TEST_TIME 59 9439
TEST_TIME 60 9431
TEST_TIME 61 9529
TEST_TIME 62 9467
TEST_TIME 63 9565
TEST_TIME 64 9718
TEST_TIME 65 9744
TEST_TIME 66 9694
TEST_TIME 67 9793
TEST_TIME 68 9744
TEST_TIME 69 9660
TEST_TIME 70 9666
TEST_TIME 71 9626
TEST_TIME 72 9581
TEST_TIME 73 9595
TEST_TIME 74 9549
TEST_TIME 75 9509
TEST_TIME 76 9452
TEST_TIME 77 9460
TEST_TIME 78 9378
TEST_TIME 79 9496
TEST_TIME 80 9452
TEST_TIME 81 9466
TEST_TIME 82 9543
TEST_TIME 83 9557
TEST_TIME 84 9541
TEST_TIME 85 9576
TEST_TIME 86 9682
TEST_TIME 87 9613
TEST_TIME 88 9611
TEST_TIME 89 9744
TEST_TIME 90 9735
TEST_TIME 91 9683
TEST_TIME 92 9590
TEST_TIME 93 9546
TEST_TIME 94 9558
TEST_TIME 95 9526
TEST_TIME 96 9472
TEST_TIME 97 9445
TEST_TIME 98 9441
TEST_TIME 99 9465
TEST_TIME 100 9449
TEST_TIME 101 9417
TEST_TIME 102 9455
TEST_TIME 103 9451
TEST_TIME 104 9544
TEST_TIME 105 9501
TEST_TIME 106 9534
TEST_TIME 107 9582
TEST_TIME 108 9582
TEST_TIME 109 9572
TEST_TIME 110 9540
TEST_TIME 111 9494
TEST_TIME 112 9473
TEST_TIME 113 9446
TEST_TIME 114 9455
TEST_TIME 115 9411
TEST_TIME 116 9390
TEST_TIME 117 9352
TEST_TIME 118 9335
TEST_TIME 119 9290
TEST_TIME 120 9214

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-11-2021 22:40:27 to 25-11-2021 22:40:30.

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 1524709 1524709 0 0.00 0 15801 6 0 0
doc_list_biud 1524709 1524709 0 0.00 0 34232 29 0 0
sp_add_invoice_to_stock 4979 4852 127 2.55 33 234862 20146 0 127
sp_cancel_adding_invoice 5781 5781 0 0.00 5 83023 778 0 0
sp_cancel_client_order 1716 1716 0 0.00 4 28634 389 0 0
sp_cancel_customer_reserve 567264 567264 0 0.00 1 34235 60 0 0
sp_cancel_pay_from_customer 645 645 0 0.00 3 9871 92 0 0
sp_cancel_pay_to_supplier 391 391 0 0.00 2 1530 61 0 0
sp_cancel_supplier_invoice 6396 6390 6 0.09 2 32136 864 0 6
sp_cancel_supplier_order 399 399 0 0.00 4 1034453 110736 0 0
sp_cancel_write_off 10389 10389 0 0.00 1 19888 117 0 0
sp_client_order 19862 19862 0 0.00 3 28870 355 0 0
sp_customer_reserve 453511 453511 0 0.00 0 52319 196 0 0
sp_fill_shopping_cart 30541 30541 0 0.00 1 14751 41 0 0
sp_fill_shopping_cart_clo_res 453509 453509 0 0.00 0 19828 17 0 0
sp_get_clo_for_invoice 4850 4850 0 0.00 0 20719 322 0 0
sp_kill_cost_storno 17206 17206 0 0.00 0 7810 60 0 0
sp_kill_qstorno_ret_qs2qd 1151706 1151706 0 0.00 0 34228 33 0 0
sp_kill_qty_storno 606602 606602 0 0.00 0 34232 71 0 0
sp_lock_selected_doc 20338 20338 0 0.00 0 9854 7 0 0
sp_make_cost_storno 27133 27133 0 0.00 0 15757 57 0 0
sp_multiply_rows_for_pdistr 27133 27133 0 0.00 0 12412 33 0 0
sp_multiply_rows_for_qdistr 19862 19862 0 0.00 0 25705 164 0 0
sp_pay_from_customer 6832 6832 0 0.00 4 16000 126 0 0
sp_pay_to_supplier 5638 5638 0 0.00 4 15996 133 0 0
sp_payment_common 12470 12470 0 0.00 2 14679 25 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 7327 318 0 0
sp_qd_handle_on_invoice_upd_sts 10631 10631 0 0.00 0 28844 370 0 0
sp_qd_handle_on_reserve_upd_sts 9813 9813 0 0.00 0 23682 58 0 0
sp_reserve_write_off 9813 9813 0 0.00 6 29193 191 0 0
sp_supplier_invoice 5358 5358 0 0.00 6 83601 3749 0 0
sp_supplier_order 5334 5334 0 0.00 6 26885 1219 0 0
srv_aggregate_perf_data 96 96 0 0.00 2624 195127 21005 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1972 123145 43691 0 0
srv_find_qd_qs_mism 1058909 1058909 0 0.00 0 13585 5 0 0
srv_make_invnt_saldo 535 535 0 0.00 33 133349 1243 0 0
srv_make_money_saldo 754 754 0 0.00 0 710 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 240677 525616 369572 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4852 4852 0 0.00 0 13078 196 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 0 1682 75 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 4995 15 0 0
t$perf-norm:sp_cancel_customer_reserve 716 716 0 0.00 0 5941 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 645 645 0 0.00 0 3902 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 391 391 0 0.00 0 263 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 2458 20 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 25842 1527 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 6453 11 0 0
t$perf-norm:sp_client_order 19862 19862 0 0.00 0 15879 21 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 4391 10 0 0
t$perf-norm:sp_pay_from_customer 6832 6832 0 0.00 0 7779 11 0 0
t$perf-norm:sp_pay_to_supplier 5638 5638 0 0.00 0 7391 14 0 0
t$perf-norm:sp_reserve_write_off 9813 9813 0 0.00 0 13207 12 0 0
t$perf-norm:sp_supplier_invoice 5358 5358 0 0.00 0 15885 23 0 0
t$perf-norm:sp_supplier_order 5334 5334 0 0.00 0 5101 19 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 405 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 180 7 0 0
t$perf-norm:srv_make_invnt_saldo 535 535 0 0.00 0 910 25 0 0
t$perf-norm:srv_make_money_saldo 754 754 0 0.00 0 1781 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 9954 9700 254 2.55 0 21884 20 0 254
v_all_customers 36480 36480 0 0.00 0 170 1 0 0
v_all_suppliers 29504 29504 0 0.00 0 18 1 0 0
v_all_wares 39722 39722 0 0.00 0 91 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 1143 8 0 0
v_cancel_client_order 3432 3432 0 0.00 0 28534 20 0 0
v_cancel_customer_prepayment 1290 1290 0 0.00 0 4696 12 0 0
v_cancel_customer_reserve 1432 1432 0 0.00 0 2281 7 0 0
v_cancel_payment_to_supplier 782 782 0 0.00 0 1519 5 0 0
v_cancel_supplier_invoice 768 756 12 1.56 0 4400 24 0 12
v_cancel_supplier_order 798 798 0 0.00 0 247 4 0 0
v_cancel_write_off 2102 2102 0 0.00 0 3903 16 0 0
v_max_id_clo_ord 5332 5332 0 0.00 0 1432 2 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 2395 18 0 0
v_max_id_ord_sup 5347 5347 0 0.00 0 5118 6 0 0
v_max_non_paid_invoice 1565 1565 0 0.00 0 568 2 0 0
v_max_non_paid_realizn 3529 3529 0 0.00 0 5931 5 0 0
v_min_id_clo_ord 5332 5332 0 0.00 0 2575 6 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 4941 15 0 0
v_min_id_ord_sup 5347 5347 0 0.00 0 4259 7 0 0
v_min_non_paid_invoice 5638 5638 0 0.00 0 14674 15 0 0
v_min_non_paid_realizn 6832 6832 0 0.00 0 5497 21 0 0
v_our_firm 9846 9846 0 0.00 0 14 1 0 0
v_reserve_write_off 19624 19624 0 0.00 0 29126 11 0 0
x_lock_depdocs_on_canc_invoice 11793 11793 0 0.00 0 21610 121 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 12013 373 0 0
x_make_qstorno_1000_1200 5334 5334 0 0.00 2 26876 1131 0 0
x_make_qstorno_1200_2000 5358 5358 0 0.00 3 83240 3602 0 0
x_make_qstorno_2100_3300 453511 453511 0 0.00 0 52266 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-11-2021 22:40:31 to 25-11-2021 22:40:35.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:04 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:06 29210 8192000000 9902723936 10213527552 992279232 10.804 100 88 174 1 1021312160 5805984 23226944
20:10:40 24103 8192000000 9941476288 10259156992 1016982112 11.043 100 89 178 0 1047968480 8009488 22976880
20:12:09 19366 8192000000 9974263744 10307416064 1056811280 11.426 100 78 156 0 1086181312 9387776 19982256
20:13:32 17136 8192000000 10038018576 10389749760 1122861024 12.054 100 75 150 0 1151456736 10442976 18152736
20:14:51 9403 8192000000 10094724832 10456457216 1181199232 12.601 100 78 156 0 1211262224 12695712 17367280
20:16:05 4261 8192000000 10131212112 10502471680 1218006864 12.943 100 80 161 0 1249355744 13133904 18214976
20:17:13 2979 8192000000 10174288272 10549710848 1252544336 13.262 100 86 172 0 1285420048 14366816 18508896
20:18:17 1493 8192000000 10194679728 10574872576 1278214624 13.497 100 94 187 1 1312915616 13932144 20768848
20:19:20 31128 8192000000 10200316272 10598264832 1299378368 13.690 100 56 113 5 1321216384 7872512 13965504
20:20:53 9880 8192000000 10189334640 10605334528 1307993888 13.768 100 17 34 0 1318163952 6448704 3721360
20:22:04 5061 8192000000 10203214352 10620313600 1319239120 13.870 100 20 40 1 1330206816 6597072 4370624
20:23:10 5557 8192000000 10207113504 10627477504 1323933584 13.912 100 15 30 0 1332605456 5469680 3202192
20:24:17 11954 8192000000 10210773232 10630254592 1327980512 13.949 100 17 35 1 1338310608 5369232 4960864
20:25:30 42969 8192000000 10219931440 10639396864 1330398592 13.971 100 21 42 1 1345454288 10349488 4706208
20:27:14 5360 8192000000 10216351536 10635276288 1320340960 13.880 99 33 66 2 1338196000 10845040 7010000
20:28:20 5346 8192000000 10209188608 10623418368 1312148960 13.806 100 29 58 2 1325515776 6862320 6504496
20:29:29 69764 8192000000 10157208880 10566893568 1195271328 12.732 92 44 86 4 1210890624 5063920 10555376
20:31:42 5745 8192000000 10147046032 10542342144 1248769104 13.227 99 38 77 0 1264718624 7278176 8671344
20:32:49 5777 8192000000 10150699872 10549071872 1255772448 13.291 100 26 51 2 1267522592 5727984 6022160
20:33:57 8248 8192000000 10175072096 10582638592 1278958080 13.503 100 28 56 0 1295068224 9097024 7013120
20:35:05 49088 8192000000 10183470352 10595475456 1291823024 13.621 100 43 88 2 1308183648 5586816 10773808
20:36:55 6609 8192000000 10205263792 10612260864 1302774400 13.720 100 23 46 1 1314559824 5475424 6310000
20:38:04 33369 8192000000 10227450208 10637455360 1327048720 13.940 100 39 78 0 1343777904 7538496 9190688
20:39:51 13405 8192000000 10273172864 10678775808 1353886736 14.182 100 70 138 1 1384435408 15073312 15475360
20:41:07 49502 8192000000 10264354256 10686087168 1357630976 14.216 100 39 78 1 1379801120 13634928 8535216
20:43:05 9588 8192000000 10288411232 10709315584 1372095136 14.346 100 46 93 2 1402388752 19362944 10930672
20:44:20 31513 8192000000 10294489792 10723696640 1367996272 14.309 99 35 69 1 1399536624 23372288 8168064
20:45:58 3986 8192000000 10332169664 10752393216 1398862800 14.585 100 64 127 3 1440968496 26963872 15141824
20:47:06 2590 8192000000 10351538656 10768384000 1409989440 14.684 100 72 142 2 1457593504 31386752 16217312
20:48:12 11800 8192000000 10360852256 10782674944 1421498240 14.786 100 63 126 0 1469474944 33039248 14937456
20:49:37 7898 8192000000 10370269728 10796376064 1430922592 14.869 100 56 112 0 1479643904 35046944 13674368
20:50:49 2608 8192000000 10379784240 10794319872 1424533664 14.813 100 81 160 1 1483213504 38921024 19758816
20:51:58 2861 8192000000 10385138016 10794336256 1417742000 14.753 99 85 170 1 1477836480 39444784 20649696
20:53:05 2143 8192000000 10384985440 10799558656 1422085936 14.791 100 82 163 4 1483926800 41731792 20109072
20:54:12 2156 8192000000 10371214368 10777878528 1401663584 14.610 100 93 183 2 1472234576 47638448 22932544
20:55:22 5521 8192000000 10381744560 10792390656 1405821568 14.647 100 79 156 1 1481321296 55501408 19998320
20:56:32 3137 8192000000 10371869968 10777092096 1397138720 14.570 100 87 173 3 1470966496 52407904 21419872
20:57:42 1970 8192000000 10347895680 10752368640 1380170336 14.418 100 88 176 1 1450606496 47433136 23003024
20:58:49 2897 8192000000 10346998608 10754674688 1376137968 14.382 100 76 152 1 1450454960 53922752 20394240
20:59:59 10969 8192000000 10336904016 10762858496 1377318880 14.393 100 40 80 2 1442609504 53973264 11317360
21:01:17 11853 8192000000 10307491120 10738221056 1366636016 14.297 100 37 74 0 1412721792 35383376 10702400
21:02:32 7915 8192000000 10327809088 10752442368 1373665280 14.360 100 47 92 0 1430997552 44348864 12983408
21:03:43 17194 8192000000 10309604176 10745004032 1360871536 14.245 99 31 62 2 1402200976 33123600 8205840
21:05:04 21345 8192000000 10299348400 10736250880 1361246272 14.249 99 18 36 0 1398919920 32424272 5249376
21:06:35 25650 8192000000 10286882720 10727526400 1353055856 14.175 100 30 60 2 1390033664 28734128 8243680
21:08:01 110244 8192000000 10281452096 10726625280 1338106976 14.040 97 29 58 0 1359361808 14887760 6367072
21:10:54 28887 8192000000 10280571456 10711986176 1354137216 14.185 99 36 73 4 1373059008 9583408 9338384
21:12:47 62683 8192000000 10265658928 10722381824 1369563424 14.323 100 11 22 1 1382999488 11639536 1796528
21:15:06 58806 8192000000 10257000176 10711322624 1349140992 14.140 98 10 21 0 1362920992 11497296 2282704
21:17:13 33051 8192000000 10277599680 10727604224 1368129120 14.310 99 12 24 0 1382133264 10873344 3130800
21:19:02 61514 8192000000 10290636032 10735726592 1367977120 14.309 99 23 46 1 1383058192 9713568 5367504
21:21:08 86597 8192000000 10286855536 10741686272 1376111504 14.382 99 35 70 1 1398361440 13795904 8454032
21:23:37 54223 8192000000 10286141504 10741047296 1389561392 14.502 100 17 34 1 1400861712 7843136 3457184
21:25:35 26148 8192000000 10288078272 10747326464 1391735568 14.521 100 36 72 3 1412485248 11878672 8871008
21:27:24 52462 8192000000 10304282272 10768441344 1407483952 14.662 100 11 22 0 1424061664 13749008 2828704
21:29:17 51096 8192000000 10292174528 10752753664 1394371088 14.545 100 29 58 0 1412350848 10316144 7663616
21:31:11 70677 8192000000 10283280336 10747052032 1370158112 14.328 98 14 28 0 1384937344 11881824 2897408
21:33:22 68742 8192000000 10285669712 10752581632 1384672384 14.458 99 8 16 1 1398465456 11993184 1799888
21:35:31 40789 8192000000 10313140384 10769186816 1407815600 14.665 100 36 71 0 1425917072 10232288 7869184
21:37:15 46642 8192000000 10284444768 10724691968 1352385104 14.169 98 22 43 0 1364838720 7938096 4515520
21:39:03 38116 8192000000 10275662704 10732625920 1347618624 14.126 98 28 56 0 1367750416 14081504 6050288
21:40:42 57152 8192000000 10256291344 10713456640 1312573936 13.809 96 11 22 1 1333199360 18184496 2440928
21:42:39 67964 8192000000 10268651200 10715787264 1300734176 13.702 95 13 26 0 1317096368 13696112 2666080
21:44:52 72663 8192000000 10251937648 10694586368 1289172208 13.597 95 15 30 0 1301678736 9261904 3244624
21:47:06 123145 8192000000 10239571888 10681683968 1319467008 13.872 98 30 60 0 1335184432 8720016 6997408
21:50:16 14738 8192000000 10256561488 10695839744 1344200336 14.095 100 31 62 0 1361769168 11038896 6529936
21:51:32 89869 8192000000 10261571888 10716585984 1333038064 13.995 98 12 24 1 1348282512 11755984 3488464
21:54:02 18785 8192000000 10278681008 10709643264 1356473072 14.206 99 46 92 1 1381289712 14003392 10813248
21:55:22 54597 8192000000 10276965264 10734522368 1370678384 14.333 100 9 18 0 1389470864 16693984 2098496
21:59:17 100475 8192000000 10256451648 10719551488 1322371200 13.898 97 18 35 0 1343561136 17258992 3930944
22:01:59 29547 8192000000 10285314736 10723938304 1356800176 14.209 99 26 51 0 1374089360 10623920 6665264
22:03:30 104547 8192000000 10286106960 10757357568 1338448128 14.043 98 10 19 1 1363268736 22910928 1909680
22:06:15 64832 8192000000 10276869568 10746265600 1362709008 14.262 99 29 58 0 1388605056 19406992 6489056
22:08:23 91728 8192000000 10307615584 10744639488 1343962592 14.093 97 26 52 1 1361330752 12135920 5232240
22:11:05 53803 8192000000 10294780160 10742583296 1379589408 14.413 100 47 94 2 1403056752 12843744 10623600
22:13:07 72687 8192000000 10272001696 10738216960 1358261424 14.222 98 5 10 0 1365943808 6826016 856368
22:15:56 58950 8192000000 10309928272 10775769088 1405538624 14.644 100 12 24 0 1418833712 10647568 2647520
22:17:56 62820 8192000000 10302483088 10761699328 1361292608 14.249 97 19 38 0 1379022144 13613472 4116064
22:19:59 35472 8192000000 10313985088 10764980224 1394376320 14.545 100 33 66 1 1412141440 10584160 7180960
22:21:35 78197 8192000000 10294251536 10763669504 1358187824 14.221 97 7 14 2 1380564368 20627488 1749056
22:24:00 50475 8192000000 10295559024 10744864768 1346969040 14.120 97 25 50 1 1366518608 13972128 5577440
22:26:02 108143 8192000000 10279732544 10723028992 1329058384 13.959 96 19 38 2 1342601024 9575776 3966864
22:28:50 31875 8192000000 10296212800 10738409472 1380740416 14.423 100 62 123 3 1407377232 13413440 13223376
22:30:57 60669 8192000000 10259927936 10719756288 1355699920 14.199 99 16 32 1 1370990080 10989472 4300688
22:32:58 40171 8192000000 10246101568 10709467136 1344489984 14.098 99 3 6 0 1354044352 8963344 591024
22:35:02 55128 8192000000 10247001328 10696548352 1309938640 13.786 96 17 34 0 1328471088 14632832 3899616
22:36:59 68854 8192000000 9257705376 9529835520 411844688 4.786 30 10 20 2 426874048 12537776 2491584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-11-2021 22:40:37 to 25-11-2021 22:40:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-11-2021 22:40:53 to 25-11-2021 22:40:55.

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          236588
MON$OLDEST_ACTIVE               237010
MON$OLDEST_SNAPSHOT             237010
MON$NEXT_TRANSACTION            237010
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                       2031008
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 ---
:364: 
:365: mscs-test-161.budzdorov.ru    Thu Nov 25 22:38:36 2021
:366:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:367: 
--- end of diff output ---

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

25.11.21 22:40:56. 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-25 22:40:58 No severe PSQL-related problems occured
25.11.21 22:40:59. End of report.