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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15917024 kB
MemAvailable 30805220 kB
Buffers 0 kB
Cached 15802740 kB
SwapCached 2456 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-09 19:37:55
DTS_END                         2021-11-09 19:37:55.8820
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/51816
TRN_ID                          237732
ATT_ID                          635
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: 9483.11 18021 1137973
customer order: creation 175.90 360 21108
customer order: refuse 14.64 380 1757
order to supplier: creation 44.76 1406 5371
order to supplier: removal 3.49 102013 419
invoice (draft): creation 47.23 3271 5668
invoice (draft): removal 55.73 742 6688
invoice accept: apply 41.58 16952 4989
invoice accept: cancel 49.68 769 5962
customer reserve: creation 3857.88 167 462945
customer reserve: removal 4894.92 58 587390
realization accept: apply 85.02 200 10202
realization accept: cancel 81.39 107 9767
payment from customer: creation 60.93 117 7312
payment from customer: removal 5.79 99 695
payment to supplier: creation 49.85 137 5982
payment to supplier: removal 3.16 78 379
service: total inventory turnovers 4.20 1287 504
service: total monetary turnovers 6.20 37 744
service: aggregate perf. data 0.73 26881 87
service: refresh index statistics 0.03 205355 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-11-2021 19:39:39 to 09-11-2021 19:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2468
TEST_TIME 2 3104
TEST_TIME 3 3998
TEST_TIME 4 4062
TEST_TIME 5 4362
TEST_TIME 6 5070
TEST_TIME 7 5025
TEST_TIME 8 5017
TEST_TIME 9 5135
TEST_TIME 10 5143
TEST_TIME 11 5342
TEST_TIME 12 5976
TEST_TIME 13 5819
TEST_TIME 14 5810
TEST_TIME 15 6019
TEST_TIME 16 6560
TEST_TIME 17 6819
TEST_TIME 18 7616
TEST_TIME 19 7731
TEST_TIME 20 7908
TEST_TIME 21 8490
TEST_TIME 22 8714
TEST_TIME 23 8772
TEST_TIME 24 8713
TEST_TIME 25 8653
TEST_TIME 26 8688
TEST_TIME 27 8533
TEST_TIME 28 9456
TEST_TIME 29 9787
TEST_TIME 30 10436
TEST_TIME 31 10301
TEST_TIME 32 10282
TEST_TIME 33 10369
TEST_TIME 34 10430
TEST_TIME 35 10368
TEST_TIME 36 10170
TEST_TIME 37 10161
TEST_TIME 38 9961
TEST_TIME 39 9988
TEST_TIME 40 9959
TEST_TIME 41 9925
TEST_TIME 42 9720
TEST_TIME 43 9726
TEST_TIME 44 9650
TEST_TIME 45 9554
TEST_TIME 46 9551
TEST_TIME 47 9536
TEST_TIME 48 9639
TEST_TIME 49 9592
TEST_TIME 50 9701
TEST_TIME 51 9659
TEST_TIME 52 9755
TEST_TIME 53 9648
TEST_TIME 54 9870
TEST_TIME 55 9849
TEST_TIME 56 9935
TEST_TIME 57 9879
TEST_TIME 58 9826
TEST_TIME 59 9822
TEST_TIME 60 9867
TEST_TIME 61 9943
TEST_TIME 62 9964
TEST_TIME 63 9990
TEST_TIME 64 9992
TEST_TIME 65 9953
TEST_TIME 66 9821
TEST_TIME 67 9759
TEST_TIME 68 9776
TEST_TIME 69 9801
TEST_TIME 70 9754
TEST_TIME 71 9749
TEST_TIME 72 9690
TEST_TIME 73 9729
TEST_TIME 74 9693
TEST_TIME 75 9663
TEST_TIME 76 9605
TEST_TIME 77 9555
TEST_TIME 78 9543
TEST_TIME 79 9568
TEST_TIME 80 9512
TEST_TIME 81 9501
TEST_TIME 82 9522
TEST_TIME 83 9515
TEST_TIME 84 9556
TEST_TIME 85 9575
TEST_TIME 86 9555
TEST_TIME 87 9591
TEST_TIME 88 9581
TEST_TIME 89 9602
TEST_TIME 90 9595
TEST_TIME 91 9664
TEST_TIME 92 9663
TEST_TIME 93 9703
TEST_TIME 94 9766
TEST_TIME 95 9667
TEST_TIME 96 9634
TEST_TIME 97 9600
TEST_TIME 98 9608
TEST_TIME 99 9539
TEST_TIME 100 9510
TEST_TIME 101 9466
TEST_TIME 102 9454
TEST_TIME 103 9451
TEST_TIME 104 9497
TEST_TIME 105 9490
TEST_TIME 106 9455
TEST_TIME 107 9437
TEST_TIME 108 9473
TEST_TIME 109 9455
TEST_TIME 110 9508
TEST_TIME 111 9533
TEST_TIME 112 9518
TEST_TIME 113 9585
TEST_TIME 114 9568
TEST_TIME 115 9563
TEST_TIME 116 9647
TEST_TIME 117 9590
TEST_TIME 118 9562
TEST_TIME 119 9532
TEST_TIME 120 9491

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-11-2021 19:39:42 to 09-11-2021 19:39:46.

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 1567560 1567560 0 0.00 0 24626 5 0 0
doc_list_biud 1567559 1567559 0 0.00 0 52419 28 0 0
sp_add_invoice_to_stock 5095 4989 106 2.08 15 201719 16952 0 106
sp_cancel_adding_invoice 5962 5962 0 0.00 5 80576 770 0 0
sp_cancel_client_order 1757 1757 0 0.00 4 26032 380 0 0
sp_cancel_customer_reserve 587390 587390 0 0.00 1 47760 59 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 8222 100 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 3367 79 0 0
sp_cancel_supplier_invoice 6697 6688 9 0.13 2 30386 742 0 9
sp_cancel_supplier_order 419 419 0 0.00 8 977346 102014 0 0
sp_cancel_write_off 9767 9767 0 0.00 1 22802 108 0 0
sp_client_order 21108 21108 0 0.00 3 52460 360 0 0
sp_customer_reserve 462945 462945 0 0.00 0 59119 167 0 0
sp_fill_shopping_cart 32143 32143 0 0.00 1 13934 35 0 0
sp_fill_shopping_cart_clo_res 462934 462934 0 0.00 0 14676 14 0 0
sp_get_clo_for_invoice 4976 4976 0 0.00 0 20154 287 0 0
sp_kill_cost_storno 16803 16803 0 0.00 0 9603 49 0 0
sp_kill_qstorno_ret_qs2qd 1191654 1191654 0 0.00 0 47756 32 0 0
sp_kill_qty_storno 627161 627161 0 0.00 0 52418 68 0 0
sp_lock_selected_doc 20946 20946 0 0.00 0 7540 4 0 0
sp_make_cost_storno 28472 28472 0 0.00 0 14338 51 0 0
sp_multiply_rows_for_pdistr 28472 28472 0 0.00 0 7845 27 0 0
sp_multiply_rows_for_qdistr 21108 21108 0 0.00 0 20827 145 0 0
sp_pay_from_customer 7312 7312 0 0.00 4 10946 118 0 0
sp_pay_to_supplier 5982 5982 0 0.00 5 10550 137 0 0
sp_payment_common 13294 13294 0 0.00 1 9992 24 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 26026 341 0 0
sp_qd_handle_on_invoice_upd_sts 10938 10938 0 0.00 0 52418 311 0 0
sp_qd_handle_on_reserve_upd_sts 10202 10202 0 0.00 0 52190 88 0 0
sp_reserve_write_off 10202 10202 0 0.00 6 52275 201 0 0
sp_supplier_invoice 5668 5668 0 0.00 6 85297 3272 0 0
sp_supplier_order 5371 5371 0 0.00 5 70017 1406 0 0
srv_aggregate_perf_data 87 87 0 0.00 3287 274067 26882 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 3058 144354 43718 0 0
srv_find_qd_qs_mism 1090183 1090183 0 0.00 0 24626 5 0 0
srv_make_invnt_saldo 504 504 0 0.00 49 54386 1287 0 0
srv_make_money_saldo 744 744 0 0.00 1 9848 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 132299 318222 205355 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4990 4990 0 0.00 0 23359 197 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 3818 99 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 8162 19 0 0
t$perf-norm:sp_cancel_customer_reserve 673 673 0 0.00 0 2837 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 3303 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 3533 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 2270 21 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 25891 1906 0 0
t$perf-norm:sp_cancel_write_off 1015 1015 0 0.00 0 2816 6 0 0
t$perf-norm:sp_client_order 21108 21108 0 0.00 0 18047 19 0 0
t$perf-norm:sp_customer_reserve 955 955 0 0.00 0 8182 14 0 0
t$perf-norm:sp_pay_from_customer 7312 7312 0 0.00 0 8034 15 0 0
t$perf-norm:sp_pay_to_supplier 5982 5982 0 0.00 0 13065 26 0 0
t$perf-norm:sp_reserve_write_off 10202 10202 0 0.00 0 6806 12 0 0
t$perf-norm:sp_supplier_invoice 5668 5668 0 0.00 0 5873 18 0 0
t$perf-norm:sp_supplier_order 5371 5371 0 0.00 0 23467 21 0 0
t$perf-norm:srv_aggregate_perf_data 87 87 0 0.00 0 4126 54 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 759 17 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 3748 24 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 1687 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10162 9950 212 2.08 0 7585 20 0 212
v_all_customers 38596 38596 0 0.00 0 136 0 0 0
v_all_suppliers 30906 30906 0 0.00 0 20 1 0 0
v_all_wares 42216 42216 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 2260 8 0 0
v_cancel_client_order 3514 3514 0 0.00 0 6863 11 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 8206 18 0 0
v_cancel_customer_reserve 1346 1346 0 0.00 0 4209 7 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 2339 11 0 0
v_cancel_supplier_invoice 836 818 18 2.15 0 1814 19 0 18
v_cancel_supplier_order 838 838 0 0.00 0 1708 6 0 0
v_cancel_write_off 2030 2030 0 0.00 0 7303 20 0 0
v_max_id_clo_ord 5368 5368 0 0.00 0 867 1 0 0
v_max_id_clo_res 955 955 0 0.00 0 3462 14 0 0
v_max_id_ord_sup 5666 5666 0 0.00 0 2778 3 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 71 2 0 0
v_max_non_paid_realizn 3888 3888 0 0.00 0 3205 5 0 0
v_min_id_clo_ord 5368 5368 0 0.00 0 4388 14 0 0
v_min_id_clo_res 955 955 0 0.00 0 9070 22 0 0
v_min_id_ord_sup 5666 5666 0 0.00 0 4183 8 0 0
v_min_non_paid_invoice 5982 5982 0 0.00 0 9959 17 0 0
v_min_non_paid_realizn 7312 7312 0 0.00 0 9987 19 0 0
v_our_firm 10468 10468 0 0.00 0 13 1 0 0
v_reserve_write_off 20402 20402 0 0.00 0 8991 8 0 0
x_lock_depdocs_on_canc_invoice 12241 12241 0 0.00 0 18802 109 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 9847 365 0 0
x_make_qstorno_1000_1200 5371 5371 0 0.00 1 66077 1324 0 0
x_make_qstorno_1200_2000 5668 5668 0 0.00 2 85278 3153 0 0
x_make_qstorno_2100_3300 462945 462945 0 0.00 0 59117 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-11-2021 19:39:47 to 09-11-2021 19:39:51.

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:07:59 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:59 32304 8192000000 9904068784 10214346752 984444352 10.727 100 87 174 1 1013516624 6312928 22759344
17:10:35 22864 8192000000 9955070656 10277371904 1028447952 11.153 100 87 174 0 1059458160 7939904 23070304
17:12:11 24970 8192000000 10011640112 10348961792 1088480304 11.728 100 79 158 0 1117690640 10100432 19109904
17:13:42 16286 8192000000 10046135280 10398896128 1132421136 12.144 100 81 162 1 1163451232 11693488 19336608
17:15:03 8130 8192000000 10096224576 10457288704 1179340064 12.584 100 75 150 1 1209443104 13093504 17009536
17:16:15 5421 8192000000 10130465376 10499371008 1217308496 12.937 100 83 166 2 1249092736 13590480 18193760
17:17:22 4102 8192000000 10153745056 10535747584 1248400688 13.224 100 82 165 0 1278962368 12118480 18443200
17:18:28 4528 8192000000 10190960688 10568323072 1271805136 13.438 100 91 181 0 1306785904 15430352 19550416
17:19:34 1679 8192000000 10212523856 10594594816 1292659248 13.628 100 95 190 0 1327404816 13561840 21183728
17:20:37 4063 8192000000 10209747136 10601902080 1300323152 13.698 100 78 155 0 1328022368 9840336 17858880
17:21:43 4339 8192000000 10202505568 10607874048 1308580464 13.773 100 44 86 1 1327902832 9367152 9955216
17:22:49 10399 8192000000 10201271248 10615685120 1315133104 13.833 100 26 53 2 1327942560 6814512 5994944
17:24:00 12600 8192000000 10208447360 10624159744 1321956704 13.894 100 12 24 0 1329927520 5384336 2586480
17:25:15 28188 8192000000 10215750800 10635218944 1324900144 13.921 100 34 68 1 1339397168 5793920 8703104
17:26:51 1592 8192000000 10237249344 10651111424 1332581600 13.990 100 67 128 4 1353033808 5587312 14864896
17:27:55 8670 8192000000 10220175712 10641731584 1329707152 13.965 100 27 54 1 1342562896 6920176 5935568
17:29:04 70151 8192000000 10192433664 10611154944 1242555664 13.170 94 44 88 1 1258760160 5938928 10265568
17:31:17 5565 8192000000 10154890224 10556092416 1232090272 13.073 97 33 66 1 1246405824 6721280 7594272
17:32:23 6987 8192000000 10129433328 10530074624 1230823568 13.062 98 23 46 1 1241598880 5382576 5392736
17:33:31 41049 8192000000 10146244288 10538237952 1233464944 13.086 98 35 70 2 1248316704 5627760 9224000
17:35:17 6440 8192000000 10167929232 10575179776 1277235536 13.488 100 26 52 0 1289727056 6198464 6293056
17:36:24 9408 8192000000 10189738800 10597126144 1290635584 13.610 100 13 26 2 1302711648 9251248 2824816
17:37:34 7306 8192000000 10211127936 10621984768 1314276240 13.825 100 24 48 1 1325041360 5143008 5622112
17:38:51 28188 8192000000 10233212464 10647023616 1330760240 13.974 100 53 104 0 1353421808 10870752 11790816
17:40:45 18123 8192000000 10238402528 10661175296 1331876016 13.984 99 41 82 1 1352135712 10629648 9630048
17:42:07 9257 8192000000 10281589312 10693718016 1362091984 14.256 100 63 123 2 1388445632 12440528 13913120
17:43:21 19132 8192000000 10279960640 10708471808 1374878224 14.371 100 38 76 1 1398241984 14653360 8710400
17:44:45 19066 8192000000 10308974048 10734886912 1387409888 14.483 100 52 104 2 1418671936 18307680 12954368
17:46:08 5762 8192000000 10331137648 10750763008 1403967520 14.630 100 71 142 0 1442540288 20626464 17946304
17:47:16 20753 8192000000 10341917856 10767872000 1409224400 14.677 100 51 100 1 1447475280 25316448 12934432
17:48:46 14083 8192000000 10363201024 10787729408 1424643712 14.814 100 58 116 1 1470962944 31565328 14753904
17:50:06 13147 8192000000 10342537840 10770939904 1416829888 14.745 100 40 80 3 1453067168 25970752 10266528
17:51:21 20570 8192000000 10333696128 10757267456 1375996048 14.381 97 52 104 0 1416886016 27470304 13419664
17:52:45 5158 8192000000 10348483456 10763673600 1403703664 14.628 100 69 136 2 1454007744 33291584 17012496
17:53:56 3057 8192000000 10354019440 10771017728 1401932336 14.612 100 67 132 2 1455435744 37282128 16221280
17:55:04 5242 8192000000 10330979792 10746757120 1374229584 14.365 100 64 126 3 1435288960 45300144 15759232
17:56:13 11441 8192000000 10324895664 10749136896 1382853904 14.442 100 47 94 1 1431757056 37346960 11556192
17:57:38 6737 8192000000 10321989600 10747244544 1368569152 14.314 100 53 106 1 1426912400 44254720 14088528
17:58:51 4454 8192000000 10357767120 10777997312 1393525248 14.537 100 64 126 1 1459068800 49520240 16023312
17:59:57 10771 8192000000 10322073728 10741694464 1344226496 14.096 97 40 78 1 1392272992 38284848 9761648
18:01:20 17958 8192000000 10319494336 10741723136 1362870816 14.263 99 45 90 1 1417778656 41546848 13360992
18:03:05 21225 8192000000 10330231856 10764431360 1383761808 14.450 100 36 72 1 1438243696 44568368 9913520
18:04:35 23014 8192000000 10338044944 10772627456 1377550496 14.395 100 27 54 1 1437233184 51956992 7725696
18:06:03 19555 8192000000 10301665120 10744578048 1357224576 14.212 100 24 48 0 1403919936 40063840 6631520
18:07:24 79827 8192000000 10277311216 10715492352 1335550816 14.017 98 9 16 1 1355695600 19080640 1064144
18:09:46 69119 8192000000 10269573200 10711756800 1311092416 13.796 96 6 12 1 1327396752 15291568 1012768
18:11:58 70379 8192000000 10289571808 10712965120 1336069696 14.022 97 47 92 3 1354302608 7292896 10940016
18:14:09 141134 8192000000 10270462480 10722512896 1341681232 14.073 98 36 72 0 1360660112 10882928 8095952
18:17:52 17730 8192000000 10270304608 10706464768 1348070224 14.130 99 31 62 1 1362479904 7655200 6754480
18:19:12 51866 8192000000 10292382016 10739724288 1380665120 14.422 100 19 38 1 1394445888 9040080 4740688
18:21:07 29818 8192000000 10308673232 10756038656 1393660752 14.539 100 21 41 1 1412346736 13880608 4805376
18:22:43 10089 8192000000 10323358096 10764550144 1399489584 14.590 100 60 121 0 1432191616 16976704 15725328
18:23:54 43473 8192000000 10330767408 10790735872 1404596720 14.636 100 16 32 0 1431637200 22869440 4171040
18:25:43 58528 8192000000 10331787424 10794852352 1420419872 14.776 100 13 26 1 1444733264 20538048 3775344
18:27:45 59539 8192000000 10298887936 10765176832 1378944336 14.407 98 12 24 0 1399472256 17644208 2883712
18:29:50 58099 8192000000 10311278944 10764115968 1368625824 14.315 98 16 32 0 1389237296 16831616 3779856
18:31:57 106864 8192000000 10295219104 10760962048 1358787072 14.226 97 14 28 1 1373716000 11913648 3015280
18:34:55 27552 8192000000 10307899072 10757652480 1392309200 14.526 100 52 102 2 1414514880 10808880 11396800
18:36:53 25569 8192000000 10298255504 10747428864 1354739408 14.190 97 29 58 0 1375777200 15148080 5889712
18:38:19 144353 8192000000 10300411536 10750959616 1310299744 13.789 94 10 20 0 1327782320 15528688 1953888
18:41:46 93308 8192000000 10271660784 10724990976 1342788736 14.083 97 15 30 0 1356450864 10331696 3330432
18:44:35 77026 8192000000 10286622400 10735607808 1308558880 13.773 94 24 48 1 1328507504 14596960 5351664
18:47:01 16100 8192000000 10258671808 10695057408 1310519616 13.791 96 31 62 2 1327671408 10559504 6592288
18:48:19 60993 8192000000 10232895632 10684923904 1289937648 13.604 96 15 31 1 1304347104 10809760 3599696
18:50:40 67723 8192000000 10244847952 10707128320 1336987152 14.030 99 12 25 0 1352128640 12262944 2878544
18:52:48 28345 8192000000 10242820016 10695335936 1317160608 13.851 98 18 32 0 1333990496 13827264 3002624
18:54:23 56766 8192000000 10220577344 10675400704 1309606352 13.782 99 12 24 0 1326264224 13626768 3031104
18:56:25 68218 8192000000 10234209760 10683277312 1326206128 13.933 100 20 40 0 1341928544 11854720 3867696
18:58:41 46246 8192000000 10246090320 10699997184 1327640016 13.946 99 18 36 0 1344494240 12136608 4717616
19:00:29 40680 8192000000 10246969664 10692194304 1310469968 13.790 97 18 36 0 1323612560 9016368 4126224
19:02:17 55627 8192000000 10269584720 10713706496 1348635296 14.135 100 29 58 2 1367395728 11872784 6887648
19:04:13 29903 8192000000 10264546784 10719363072 1341786384 14.074 98 9 19 0 1352023008 8295056 1941568
19:05:52 37084 8192000000 10271125600 10722979840 1343232816 14.087 98 20 40 0 1359326032 11043984 5049232
19:07:37 43579 8192000000 10292968768 10741030912 1349980256 14.147 98 26 52 1 1369984272 14633696 5370320
19:09:23 43549 8192000000 10303256720 10754211840 1385315328 14.464 100 20 40 1 1398501712 8979440 4206944
19:11:16 62941 8192000000 10300152224 10775019520 1393364880 14.536 100 8 16 1 1417234544 21990992 1878672
19:14:18 60281 8192000000 10299933344 10763304960 1379770128 14.414 99 29 58 2 1400754064 14798384 6185552
19:16:23 42763 8192000000 10297089984 10754207744 1381755424 14.432 99 5 10 0 1395883680 13156416 971840
19:18:08 49684 8192000000 10317484688 10782138368 1408588864 14.671 100 16 32 1 1425611312 13375360 3647088
19:20:04 80360 8192000000 10307820976 10782167040 1404031552 14.631 100 12 24 1 1431012288 24021952 2958784
19:22:26 45817 8192000000 10313943968 10761957376 1373986096 14.363 98 22 44 0 1385768128 6780944 5001088
19:24:19 29972 8192000000 10286502880 10740031488 1373527296 14.359 99 26 51 2 1389399808 9987504 5885008
19:25:49 43020 8192000000 10305233088 10756923392 1386928864 14.478 100 24 48 1 1403307200 10848272 5530064
19:27:32 80604 8192000000 10298479728 10764521472 1355544848 14.197 97 14 28 1 1373030272 14272400 3213024
19:29:54 131362 8192000000 10311859920 10765971456 1354400928 14.187 97 6 12 0 1376782320 21092240 1289152
19:33:06 35080 8192000000 10287379584 10739703808 1342132416 14.077 97 22 44 0 1363946720 16107664 5706640
19:34:50 81060 8192000000 10262687904 10715234304 1313983264 13.822 96 16 32 0 1331229856 13535248 3711344
19:37:15 42882 8192000000 10026736848 10450059264 1129765696 12.119 83 15 30 1 1146544880 13362704 3416480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 09-11-2021 19:40:09 to 09-11-2021 19:40:10.

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          237434
MON$OLDEST_ACTIVE               238237
MON$OLDEST_SNAPSHOT             238237
MON$NEXT_TRANSACTION            238237
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                       2044896
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.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Tue Nov  9 19:38:18 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
--- end of diff output ---

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

09.11.21 19:40:11. 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-09 19:40:14 No severe PSQL-related problems occured
09.11.21 19:40:15. End of report.